-- Logs begin at Thu 2019-02-14 05:11:58 EST, end at Mon 2025-05-05 21:43:19 EDT. -- May 05 21:42:14 volumio go-librespot[1187]: time="2025-05-05T21:42:14-04:00" level=trace msg="received accesspoint ping" May 05 21:42:15 volumio go-librespot[1187]: time="2025-05-05T21:42:15-04:00" level=trace msg="received accesspoint pong ack" May 05 21:42:16 volumio volumio[870]: verbose: New Socket.io Connection to 192.168.1.180 from 192.168.1.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 May 05 21:42:16 volumio volumio[870]: info: CoreCommandRouter::volumioGetState May 05 21:42:16 volumio volumio[870]: info: CorePlayQueue::getTrack 4 May 05 21:42:16 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 05 21:42:16 volumio volumio[870]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 05 21:42:16 volumio volumio[870]: info: Listing playlists May 05 21:42:16 volumio volumio[870]: info: CoreCommandRouter::volumioGetQueue May 05 21:42:16 volumio volumio[870]: info: CoreStateMachine::getQueue May 05 21:42:16 volumio volumio[870]: info: CorePlayQueue::getQueue May 05 21:42:16 volumio volumio[870]: info: CoreCommandRouter::volumioGetVisibleSources May 05 21:42:16 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 21:42:20 volumio go-librespot[1187]: time="2025-05-05T21:42:20-04:00" level=trace msg="sent dealer ping" May 05 21:42:20 volumio go-librespot[1187]: time="2025-05-05T21:42:20-04:00" level=trace msg="received dealer pong" May 05 21:42:20 volumio volumio[870]: info: VolumeController::SetAlsaVolume10 May 05 21:42:20 volumio volumio[870]: info: CoreStateMachine::pushState May 05 21:42:20 volumio volumio[870]: info: CorePlayQueue::getTrack 4 May 05 21:42:20 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 21:42:20 volumio volumio[870]: info: CoreCommandRouter::volumioPushState May 05 21:42:20 volumio volumio[870]: info: MRS: Pushing multiroomSync output update for this device May 05 21:42:20 volumio volumio[870]: info: MRS: Pushing multiroomSync output May 05 21:42:20 volumio volumio[870]: info: [ASDebug] CurState: play PrevState: play May 05 21:42:20 volumio volumio[870]: info: PeppySpectrum ---peppyspectrum status play May 05 21:42:20 volumio volumio[870]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 May 05 21:42:20 volumio volumio[870]: SPOTIFY: SPOTIFY VOLUME 100 May 05 21:42:20 volumio volumio[870]: SPOTIFY: VOLUMIO VOLUME 10 May 05 21:42:20 volumio volumio[870]: SPOTIFY: DELTA VOLUME ENOUGH: true May 05 21:42:20 volumio volumio[870]: info: Setting Spotify Volume from Volumio: 10 May 05 21:42:20 volumio sudo[1900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 05 21:42:20 volumio sudo[1900]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 21:42:20 volumio volumio[870]: info: VolumeController::SetAlsaVolume40 May 05 21:42:20 volumio volumio[870]: info: CoreStateMachine::pushState May 05 21:42:20 volumio volumio[870]: info: CorePlayQueue::getTrack 4 May 05 21:42:20 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 21:42:20 volumio volumio[870]: info: CoreCommandRouter::volumioPushState May 05 21:42:20 volumio volumio[870]: info: MRS: Pushing multiroomSync output update for this device May 05 21:42:20 volumio volumio[870]: info: MRS: Pushing multiroomSync output May 05 21:42:20 volumio volumio[870]: info: [ASDebug] CurState: play PrevState: play May 05 21:42:20 volumio volumio[870]: info: PeppySpectrum ---peppyspectrum status play May 05 21:42:20 volumio volumio[870]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 May 05 21:42:20 volumio volumio[870]: SPOTIFY: SPOTIFY VOLUME 10 May 05 21:42:20 volumio volumio[870]: SPOTIFY: VOLUMIO VOLUME 40 May 05 21:42:20 volumio volumio[870]: SPOTIFY: DELTA VOLUME ENOUGH: true May 05 21:42:20 volumio volumio[870]: info: Setting Spotify Volume from Volumio: 40 May 05 21:42:20 volumio systemd[1]: Started peppyspectrum Daemon. May 05 21:42:20 volumio sudo[1905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 05 21:42:20 volumio sudo[1900]: pam_unix(sudo:session): session closed for user root May 05 21:42:20 volumio sudo[1905]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 21:42:20 volumio volumio[870]: info: peppyspectrum Daemon Started May 05 21:42:20 volumio sudo[1905]: pam_unix(sudo:session): session closed for user root May 05 21:42:20 volumio volumio[870]: info: peppyspectrum Daemon Started May 05 21:42:20 volumio volumio[870]: info: VolumeController::SetAlsaVolume75 May 05 21:42:20 volumio volumio[870]: info: CoreStateMachine::pushState May 05 21:42:20 volumio volumio[870]: info: CorePlayQueue::getTrack 4 May 05 21:42:20 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 21:42:20 volumio volumio[870]: info: CoreCommandRouter::volumioPushState May 05 21:42:20 volumio volumio[870]: info: MRS: Pushing multiroomSync output update for this device May 05 21:42:20 volumio volumio[870]: info: MRS: Pushing multiroomSync output May 05 21:42:20 volumio volumio[870]: info: [ASDebug] CurState: play PrevState: play May 05 21:42:20 volumio volumio[870]: info: PeppySpectrum ---peppyspectrum status play May 05 21:42:20 volumio volumio[870]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 May 05 21:42:20 volumio volumio[870]: SPOTIFY: SPOTIFY VOLUME 40 May 05 21:42:20 volumio volumio[870]: SPOTIFY: VOLUMIO VOLUME 75 May 05 21:42:20 volumio volumio[870]: SPOTIFY: DELTA VOLUME ENOUGH: true May 05 21:42:20 volumio volumio[870]: info: Setting Spotify Volume from Volumio: 75 May 05 21:42:20 volumio sudo[1910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 05 21:42:20 volumio sudo[1910]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 21:42:20 volumio volumio[870]: info: VolumeController::SetAlsaVolume100 May 05 21:42:20 volumio sudo[1910]: pam_unix(sudo:session): session closed for user root May 05 21:42:21 volumio volumio[870]: info: CoreStateMachine::pushState May 05 21:42:21 volumio volumio[870]: info: CorePlayQueue::getTrack 4 May 05 21:42:21 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 21:42:21 volumio volumio[870]: info: CoreCommandRouter::volumioPushState May 05 21:42:21 volumio volumio[870]: info: MRS: Pushing multiroomSync output update for this device May 05 21:42:21 volumio volumio[870]: info: MRS: Pushing multiroomSync output May 05 21:42:21 volumio volumio[870]: info: [ASDebug] CurState: play PrevState: play May 05 21:42:21 volumio volumio[870]: info: PeppySpectrum ---peppyspectrum status play May 05 21:42:21 volumio volumio[870]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 05 21:42:21 volumio volumio[870]: SPOTIFY: SPOTIFY VOLUME 75 May 05 21:42:21 volumio volumio[870]: SPOTIFY: VOLUMIO VOLUME 100 May 05 21:42:21 volumio volumio[870]: SPOTIFY: DELTA VOLUME ENOUGH: true May 05 21:42:21 volumio volumio[870]: info: Setting Spotify Volume from Volumio: 100 May 05 21:42:21 volumio sudo[1914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 05 21:42:21 volumio sudo[1914]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 21:42:21 volumio volumio[870]: info: peppyspectrum Daemon Started May 05 21:42:21 volumio sudo[1914]: pam_unix(sudo:session): session closed for user root May 05 21:42:21 volumio volumio[870]: info: peppyspectrum Daemon Started May 05 21:42:21 volumio volumio[1902]: pygame 1.9.4.post1 May 05 21:42:21 volumio volumio[1902]: Hello from the pygame community. https://www.pygame.org/contribute.html May 05 21:42:21 volumio volumio[1902]: Traceback (most recent call last): May 05 21:42:21 volumio volumio[1902]: File "spectrum.py", line 715, in May 05 21:42:21 volumio volumio[1902]: pm = Spectrum(None, True) May 05 21:42:21 volumio volumio[1902]: File "spectrum.py", line 63, in __init__ May 05 21:42:21 volumio volumio[1902]: self.init_display() May 05 21:42:21 volumio volumio[1902]: File "spectrum.py", line 104, in init_display May 05 21:42:21 volumio volumio[1902]: pygame.display.init() May 05 21:42:21 volumio volumio[1902]: pygame.error: Unable to open /dev/fb1 May 05 21:42:21 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE May 05 21:42:21 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. May 05 21:42:22 volumio volumio[870]: SPOTIFY: SETTING SPOTIFY VOLUME 100 May 05 21:42:22 volumio volumio[870]: info: Sending Spotify command with payload to local API: /player/volume May 05 21:42:22 volumio go-librespot[1187]: time="2025-05-05T21:42:22-04:00" level=debug msg="update volume to 65535/65535" May 05 21:42:28 volumio go-librespot[1187]: time="2025-05-05T21:42:28-04:00" level=debug msg="put connect state because VOLUME_CHANGED" May 05 21:42:28 volumio go-librespot[1187]: time="2025-05-05T21:42:28-04:00" level=trace msg="emitting websocket event: volume" May 05 21:42:28 volumio volumio[870]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} May 05 21:42:28 volumio volumio[870]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 May 05 21:42:46 volumio volumio[870]: verbose: New Socket.io Connection to 192.168.1.180 from 192.168.1.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 May 05 21:42:46 volumio volumio[870]: info: CoreCommandRouter::volumioGetState May 05 21:42:46 volumio volumio[870]: info: CorePlayQueue::getTrack 4 May 05 21:42:46 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 05 21:42:46 volumio volumio[870]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 05 21:42:46 volumio volumio[870]: info: Listing playlists May 05 21:42:46 volumio volumio[870]: info: CoreCommandRouter::volumioGetQueue May 05 21:42:46 volumio volumio[870]: info: CoreStateMachine::getQueue May 05 21:42:46 volumio volumio[870]: info: CorePlayQueue::getQueue May 05 21:42:46 volumio volumio[870]: info: CoreCommandRouter::volumioGetVisibleSources May 05 21:42:46 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 21:42:50 volumio go-librespot[1187]: time="2025-05-05T21:42:50-04:00" level=trace msg="sent dealer ping" May 05 21:42:50 volumio go-librespot[1187]: time="2025-05-05T21:42:50-04:00" level=trace msg="received dealer pong" May 05 21:43:09 volumio volumio[870]: info: CoreCommandRouter::volumioPause May 05 21:43:09 volumio volumio[870]: info: CoreStateMachine::pause May 05 21:43:09 volumio volumio[870]: info: CoreStateMachine::stPlaybackTimer May 05 21:43:09 volumio volumio[870]: info: CoreStateMachine::servicePause May 05 21:43:09 volumio volumio[870]: info: CorePlayQueue::getTrack 4 May 05 21:43:09 volumio volumio[870]: info: CoreCommandRouter::servicePause May 05 21:43:09 volumio volumio[870]: info: ControllerMpd::pause May 05 21:43:09 volumio volumio[870]: verbose: ControllerMpd::sendMpdCommand pause May 05 21:43:09 volumio volumio[870]: info: sendMpdCommand pause took 1 milliseconds May 05 21:43:09 volumio volumio[870]: info: May 05 21:43:09 volumio volumio[870]: ---------------------------- MPD announces state update: player May 05 21:43:09 volumio volumio[870]: info: ControllerMpd::getState May 05 21:43:09 volumio volumio[870]: verbose: ControllerMpd::sendMpdCommand status May 05 21:43:09 volumio volumio[870]: info: sendMpdCommand status took 1 milliseconds May 05 21:43:09 volumio volumio[870]: verbose: ControllerMpd::parseState May 05 21:43:09 volumio volumio[870]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 21:43:09 volumio volumio[870]: info: sendMpdCommand playlistinfo took 0 milliseconds May 05 21:43:09 volumio volumio[870]: verbose: ControllerMpd::parseTrackInfo May 05 21:43:09 volumio volumio[870]: info: ControllerMpd::pushState May 05 21:43:09 volumio volumio[870]: info: CoreCommandRouter::servicePushState May 05 21:43:09 volumio volumio[870]: info: CorePlayQueue::getTrack 4 May 05 21:43:09 volumio volumio[870]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1611,"duration":296,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"259 Kbps","isStreaming":false,"title":"Hello","artist":"Adele","album":"25","uri":"NAS/public/Adele/25/01 Hello 1.m4a","trackType":"m4a"} May 05 21:43:09 volumio volumio[870]: verbose: CURRENT POSITION 4 May 05 21:43:09 volumio volumio[870]: info: CoreStateMachine::syncState stateService pause May 05 21:43:09 volumio volumio[870]: info: CoreStateMachine::syncState currentStatus pause May 05 21:43:09 volumio volumio[870]: info: CoreStateMachine::pushState May 05 21:43:09 volumio volumio[870]: info: CorePlayQueue::getTrack 4 May 05 21:43:09 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 21:43:09 volumio volumio[870]: info: CoreCommandRouter::volumioPushState May 05 21:43:09 volumio volumio[870]: info: MRS: Pushing multiroomSync output update for this device May 05 21:43:09 volumio volumio[870]: info: MRS: Pushing multiroomSync output May 05 21:43:09 volumio volumio[870]: info: CoreStateMachine::stPlaybackTimer May 05 21:43:09 volumio volumio[870]: info: ------------------------------ 31ms May 05 21:43:09 volumio volumio[870]: info: [ASDebug] CurState: pause PrevState: play May 05 21:43:09 volumio volumio[870]: info: [ASDebug] InitTimeout - Amp off in: 720 ms May 05 21:43:09 volumio volumio[870]: info: PeppySpectrum ---peppyspectrum status pause May 05 21:43:09 volumio volumio[870]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 05 21:43:09 volumio sudo[2048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 05 21:43:09 volumio sudo[2048]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 21:43:09 volumio sudo[2048]: pam_unix(sudo:session): session closed for user root May 05 21:43:09 volumio volumio[870]: info: peppyspectrum Daemon Stop May 05 21:43:10 volumio volumio[870]: info: [ASDebug] Togle GPIO: OFF May 05 21:43:15 volumio volumio[870]: verbose: New Socket.io Connection to 192.168.1.180 from 192.168.1.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 May 05 21:43:15 volumio volumio[870]: info: CoreCommandRouter::volumioGetState May 05 21:43:15 volumio volumio[870]: info: CorePlayQueue::getTrack 4 May 05 21:43:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 05 21:43:15 volumio volumio[870]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 05 21:43:15 volumio volumio[870]: info: Listing playlists May 05 21:43:15 volumio volumio[870]: info: CoreCommandRouter::volumioGetQueue May 05 21:43:15 volumio volumio[870]: info: CoreStateMachine::getQueue May 05 21:43:15 volumio volumio[870]: info: CorePlayQueue::getQueue May 05 21:43:15 volumio volumio[870]: info: CoreCommandRouter::volumioGetVisibleSources May 05 21:43:15 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 21:43:19 volumio volumio[870]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay May 05 21:43:19 volumio volumio[870]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom May 05 21:43:19 volumio volumio[870]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 21:43:19 volumio volumio[870]: TypeError: Cannot read property 'then' of undefined May 05 21:43:19 volumio volumio[870]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) May 05 21:43:19 volumio volumio[870]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) May 05 21:43:19 volumio volumio[870]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1456:26) May 05 21:43:19 volumio volumio[870]: at Socket.emit (events.js:315:20) May 05 21:43:19 volumio volumio[870]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 05 21:43:19 volumio volumio[870]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 05 21:43:19 volumio volumio[870]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 21:43:19 volumio sudo[2079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-05 21:42 May 05 21:43:19 volumio sudo[2079]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"