-- Logs begin at Thu 2019-02-14 05:11:58 EST, end at Mon 2025-05-12 16:51:58 EDT. -- May 12 16:50:02 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 12 16:50:02 volumio volumio[951]: info: Received Get System Info May 12 16:50:02 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 12 16:50:02 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 12 16:50:02 volumio volumio[951]: info: Discovery: Getting this device information May 12 16:50:02 volumio volumio[951]: info: CoreCommandRouter::volumioGetState May 12 16:50:02 volumio volumio[951]: info: CorePlayQueue::getTrack 5 May 12 16:50:02 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 16:50:04 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 12 16:50:04 volumio volumio[951]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 12 16:50:04 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 12 16:50:04 volumio volumio[951]: info: Received Get System Version May 12 16:50:04 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 16:50:04 volumio volumio[951]: info: Received Get System Info May 12 16:50:04 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 12 16:50:04 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 12 16:50:04 volumio volumio[951]: info: Discovery: Getting this device information May 12 16:50:04 volumio volumio[951]: info: CoreCommandRouter::volumioGetState May 12 16:50:04 volumio volumio[951]: info: CorePlayQueue::getTrack 5 May 12 16:50:04 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 16:50:14 volumio volumio[951]: verbose: New Socket.io Connection to 192.168.1.180 from 192.168.1.187 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 May 12 16:50:14 volumio volumio[951]: info: CoreCommandRouter::volumioGetState May 12 16:50:14 volumio volumio[951]: info: CorePlayQueue::getTrack 5 May 12 16:50:14 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 12 16:50:14 volumio volumio[951]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 12 16:50:14 volumio volumio[951]: info: CoreCommandRouter::volumioGetVisibleSources May 12 16:50:14 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 16:50:14 volumio volumio[951]: info: Listing playlists May 12 16:50:16 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 12 16:50:16 volumio volumio[951]: info: Received Get System Info May 12 16:50:16 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 12 16:50:16 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 12 16:50:16 volumio volumio[951]: info: Discovery: Getting this device information May 12 16:50:16 volumio volumio[951]: info: CoreCommandRouter::volumioGetState May 12 16:50:16 volumio volumio[951]: info: CorePlayQueue::getTrack 5 May 12 16:50:16 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 16:50:19 volumio go-librespot[1232]: time="2025-05-12T16:50:19-04:00" level=trace msg="sent dealer ping" May 12 16:50:20 volumio go-librespot[1232]: time="2025-05-12T16:50:20-04:00" level=trace msg="received dealer pong" May 12 16:50:21 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 12 16:50:21 volumio volumio[951]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 12 16:50:21 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 12 16:50:21 volumio volumio[951]: info: Received Get System Version May 12 16:50:21 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 16:50:21 volumio volumio[951]: info: Received Get System Info May 12 16:50:21 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 12 16:50:21 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 12 16:50:21 volumio volumio[951]: info: Discovery: Getting this device information May 12 16:50:21 volumio volumio[951]: info: CoreCommandRouter::volumioGetState May 12 16:50:21 volumio volumio[951]: info: CorePlayQueue::getTrack 5 May 12 16:50:21 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 16:50:22 volumio go-librespot[1232]: time="2025-05-12T16:50:22-04:00" level=trace msg="received accesspoint ping" May 12 16:50:22 volumio go-librespot[1232]: time="2025-05-12T16:50:22-04:00" level=trace msg="received accesspoint pong ack" May 12 16:50:33 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume May 12 16:50:33 volumio volumio[951]: info: Setting Remote Device Volume: http://192.168.1.180 May 12 16:50:33 volumio volumio[951]: info: May 12 16:50:33 volumio volumio[951]: ---------------------------- Client requests Volume 98 May 12 16:50:33 volumio volumio[951]: info: VolumeController::SetAlsaVolume98 May 12 16:50:33 volumio volumio[951]: info: CoreStateMachine::pushState May 12 16:50:33 volumio volumio[951]: info: CorePlayQueue::getTrack 5 May 12 16:50:33 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 16:50:33 volumio volumio[951]: info: CoreCommandRouter::volumioPushState May 12 16:50:33 volumio volumio[951]: info: MRS: Pushing multiroomSync output update for this device May 12 16:50:33 volumio volumio[951]: info: MRS: Pushing multiroomSync output May 12 16:50:33 volumio volumio[951]: info: CoreCommandRouter::volumioGetState May 12 16:50:33 volumio volumio[951]: info: CorePlayQueue::getTrack 5 May 12 16:50:33 volumio volumio[951]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 May 12 16:50:33 volumio volumio[951]: SPOTIFY: SPOTIFY VOLUME 100 May 12 16:50:33 volumio volumio[951]: SPOTIFY: VOLUMIO VOLUME 98 May 12 16:50:33 volumio volumio[951]: SPOTIFY: DELTA VOLUME ENOUGH: true May 12 16:50:33 volumio volumio[951]: info: Setting Spotify Volume from Volumio: 98 May 12 16:50:33 volumio volumio[951]: info: Done setting volume on: May 12 16:50:34 volumio volumio[951]: SPOTIFY: SETTING SPOTIFY VOLUME 98 May 12 16:50:34 volumio volumio[951]: info: Sending Spotify command with payload to local API: /player/volume May 12 16:50:34 volumio go-librespot[1232]: time="2025-05-12T16:50:34-04:00" level=debug msg="update volume to 64224/65535" May 12 16:50:35 volumio go-librespot[1232]: time="2025-05-12T16:50:35-04:00" level=debug msg="renewing login5 access token" May 12 16:50:36 volumio go-librespot[1232]: time="2025-05-12T16:50:36-04:00" level=info msg="authenticated Login5 as 31ckr6qtmuozqrelp2b7vpk4oxha" May 12 16:50:36 volumio go-librespot[1232]: time="2025-05-12T16:50:36-04:00" level=debug msg="put connect state because VOLUME_CHANGED" May 12 16:50:36 volumio go-librespot[1232]: time="2025-05-12T16:50:36-04:00" level=trace msg="emitting websocket event: volume" May 12 16:50:36 volumio volumio[951]: SPOTIFY: received: {"type":"volume","data":{"value":98,"max":100}} May 12 16:50:36 volumio volumio[951]: SPOTIFY: RECEIVED SPOTIFY VOLUME 98 May 12 16:50:49 volumio go-librespot[1232]: time="2025-05-12T16:50:49-04:00" level=trace msg="sent dealer ping" May 12 16:50:50 volumio go-librespot[1232]: time="2025-05-12T16:50:50-04:00" level=trace msg="received dealer pong" May 12 16:51:19 volumio go-librespot[1232]: time="2025-05-12T16:51:19-04:00" level=trace msg="sent dealer ping" May 12 16:51:20 volumio go-librespot[1232]: time="2025-05-12T16:51:20-04:00" level=trace msg="received dealer pong" May 12 16:51:42 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput May 12 16:51:42 volumio volumio[951]: info: MRS: Starting browser stream May 12 16:51:42 volumio volumio[951]: info: MRS: Setting this device as single May 12 16:51:42 volumio volumio[951]: info: May 12 16:51:42 volumio volumio[951]: [1747083102867] ---------------------------- Setting Multiroom Single May 12 16:51:42 volumio volumio[951]: info: MRS: disable multiroom output May 12 16:51:42 volumio volumio[951]: info: MRS: Set multiroom target PCM to volumioDiscard May 12 16:51:42 volumio volumio[951]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard May 12 16:51:43 volumio volumio[951]: info: MRS: Set multiroom target PCM to volumioLocalPlayback May 12 16:51:43 volumio volumio[951]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioLocalPlayback May 12 16:51:43 volumio volumio[951]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 May 12 16:51:43 volumio volumio[951]: info: FusionDsp - ---- read samplerate from file: 96000 May 12 16:51:43 volumio volumio[951]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 12 16:51:43 volumio volumio[951]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 May 12 16:51:43 volumio volumio[951]: info: FusionDsp - ---- read samplerate from file: 96000 May 12 16:51:43 volumio volumio[951]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 12 16:51:43 volumio volumio[951]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 12 16:51:43 volumio volumio[951]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 12 16:51:44 volumio volumio[951]: info: MRS: STOPPING volumioStreaming May 12 16:51:44 volumio sudo[8507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 12 16:51:44 volumio sudo[8507]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 16:51:44 volumio systemd[1]: Stopping VolumioStreamingService... May 12 16:51:44 volumio systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM May 12 16:51:44 volumio sudo[8510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream0.ts /tmp/hls/stream1.ts /tmp/hls/stream10.ts /tmp/hls/stream100.ts /tmp/hls/stream101.ts /tmp/hls/stream102.ts /tmp/hls/stream103.ts /tmp/hls/stream104.ts /tmp/hls/stream105.ts /tmp/hls/stream106.ts /tmp/hls/stream107.ts /tmp/hls/stream108.ts /tmp/hls/stream109.ts /tmp/hls/stream11.ts /tmp/hls/stream110.ts /tmp/hls/stream111.ts /tmp/hls/stream112.ts /tmp/hls/stream113.ts /tmp/hls/stream114.ts /tmp/hls/stream115.ts /tmp/hls/stream116.ts /tmp/hls/stream117.ts /tmp/hls/stream118.ts /tmp/hls/stream119.ts /tmp/hls/stream12.ts /tmp/hls/stream120.ts /tmp/hls/stream121.ts /tmp/hls/stream122.ts /tmp/hls/stream123.ts /tmp/hls/stream124.ts /tmp/hls/stream125.ts /tmp/hls/stream126.ts /tmp/hls/stream127.ts /tmp/hls/stream128.ts /tmp/hls/stream129.ts /tmp/hls/stream13.ts /tmp/hls/stream130.ts /tmp/hls/stream131.ts /tmp/hls/stream132.ts /tmp/hls/stream133.ts May 12 16:51:44 volumio sudo[8510]: volumio : (command continued) /tmp/hls/stream134.ts /tmp/hls/stream135.ts /tmp/hls/stream136.ts /tmp/hls/stream137.ts /tmp/hls/stream138.ts /tmp/hls/stream139.ts /tmp/hls/stream14.ts /tmp/hls/stream140.ts /tmp/hls/stream141.ts /tmp/hls/stream142.ts /tmp/hls/stream143.ts /tmp/hls/stream144.ts /tmp/hls/stream145.ts /tmp/hls/stream146.ts /tmp/hls/stream147.ts /tmp/hls/stream148.ts /tmp/hls/stream149.ts /tmp/hls/stream15.ts /tmp/hls/stream150.ts /tmp/hls/stream151.ts /tmp/hls/stream152.ts /tmp/hls/stream153.ts /tmp/hls/stream154.ts /tmp/hls/stream155.ts /tmp/hls/stream156.ts /tmp/hls/stream157.ts /tmp/hls/stream158.ts /tmp/hls/stream159.ts /tmp/hls/stream16.ts /tmp/hls/stream160.ts /tmp/hls/stream161.ts /tmp/hls/stream162.ts /tmp/hls/stream163.ts /tmp/hls/stream164.ts /tmp/hls/stream165.ts /tmp/hls/stream166.ts /tmp/hls/stream167.ts /tmp/hls/stream168.ts /tmp/hls/stream169.ts /tmp/hls/stream17.ts /tmp/hls/stream170.ts /tmp/hls/stream171.ts May 12 16:51:44 volumio sudo[8510]: volumio : (command continued) /tmp/hls/stream172.ts /tmp/hls/stream173.ts /tmp/hls/stream174.ts /tmp/hls/stream175.ts /tmp/hls/stream176.ts /tmp/hls/stream177.ts /tmp/hls/stream178.ts /tmp/hls/stream179.ts /tmp/hls/stream18.ts /tmp/hls/stream180.ts /tmp/hls/stream181.ts /tmp/hls/stream182.ts /tmp/hls/stream183.ts /tmp/hls/stream184.ts /tmp/hls/stream185.ts /tmp/hls/stream186.ts /tmp/hls/stream187.ts /tmp/hls/stream188.ts /tmp/hls/stream189.ts /tmp/hls/stream19.ts /tmp/hls/stream190.ts /tmp/hls/stream191.ts /tmp/hls/stream192.ts /tmp/hls/stream193.ts /tmp/hls/stream2.ts /tmp/hls/stream20.ts /tmp/hls/stream21.ts /tmp/hls/stream22.ts /tmp/hls/stream23.ts /tmp/hls/stream24.ts /tmp/hls/stream25.ts /tmp/hls/stream26.ts /tmp/hls/stream27.ts /tmp/hls/stream28.ts /tmp/hls/stream29.ts /tmp/hls/stream3.ts /tmp/hls/stream30.ts /tmp/hls/stream31.ts /tmp/hls/stream32.ts /tmp/hls/stream33.ts /tmp/hls/stream34.ts /tmp/hls/stream35.ts /tmp/hls/stream36.ts May 12 16:51:44 volumio sudo[8510]: volumio : (command continued) /tmp/hls/stream37.ts /tmp/hls/stream38.ts /tmp/hls/stream39.ts /tmp/hls/stream4.ts /tmp/hls/stream40.ts /tmp/hls/stream41.ts /tmp/hls/stream42.ts /tmp/hls/stream43.ts /tmp/hls/stream44.ts /tmp/hls/stream45.ts /tmp/hls/stream46.ts /tmp/hls/stream47.ts /tmp/hls/stream48.ts /tmp/hls/stream49.ts /tmp/hls/stream5.ts /tmp/hls/stream50.ts /tmp/hls/stream51.ts /tmp/hls/stream52.ts /tmp/hls/stream53.ts /tmp/hls/stream54.ts /tmp/hls/stream55.ts /tmp/hls/stream56.ts /tmp/hls/stream57.ts /tmp/hls/stream58.ts /tmp/hls/stream59.ts /tmp/hls/stream6.ts /tmp/hls/stream60.ts /tmp/hls/stream61.ts /tmp/hls/stream62.ts /tmp/hls/stream63.ts /tmp/hls/stream64.ts /tmp/hls/stream65.ts /tmp/hls/stream66.ts /tmp/hls/stream67.ts /tmp/hls/stream68.ts /tmp/hls/stream69.ts /tmp/hls/stream7.ts /tmp/hls/stream70.ts /tmp/hls/stream71.ts /tmp/hls/stream72.ts /tmp/hls/stream73.ts /tmp/hls/stream74.ts /tmp/hls/stream75.ts /tmp/hls/stream76.ts May 12 16:51:44 volumio sudo[8510]: volumio : (command continued) /tmp/hls/stream77.ts /tmp/hls/stream78.ts /tmp/hls/stream79.ts /tmp/hls/stream8.ts /tmp/hls/stream80.ts /tmp/hls/stream81.ts /tmp/hls/stream82.ts /tmp/hls/stream83.ts /tmp/hls/stream84.ts /tmp/hls/stream85.ts /tmp/hls/stream86.ts /tmp/hls/stream87.ts /tmp/hls/stream88.ts /tmp/hls/stream89.ts /tmp/hls/stream9.ts /tmp/hls/stream90.ts /tmp/hls/stream91.ts /tmp/hls/stream92.ts /tmp/hls/stream93.ts /tmp/hls/stream94.ts /tmp/hls/stream95.ts /tmp/hls/stream96.ts /tmp/hls/stream97.ts /tmp/hls/stream98.ts /tmp/hls/stream99.ts May 12 16:51:44 volumio sudo[8510]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 16:51:44 volumio sudo[8510]: pam_unix(sudo:session): session closed for user root May 12 16:51:44 volumio systemd[1]: volumioStreaming.service: Succeeded. May 12 16:51:44 volumio systemd[1]: Stopped VolumioStreamingService. May 12 16:51:44 volumio volumio[951]: ------------------------------------ BT MESSAGE: BT STATUS: running May 12 16:51:44 volumio volumio[951]: info: MRS: Removed streaming files May 12 16:51:44 volumio sudo[8507]: pam_unix(sudo:session): session closed for user root May 12 16:51:44 volumio volumio[951]: info: MRS: volumioStreaming STOPPED May 12 16:51:49 volumio go-librespot[1232]: time="2025-05-12T16:51:49-04:00" level=trace msg="sent dealer ping" May 12 16:51:50 volumio go-librespot[1232]: time="2025-05-12T16:51:50-04:00" level=trace msg="received dealer pong" May 12 16:51:50 volumio volumio[951]: info: CorePlayQueue::getQueue May 12 16:51:50 volumio volumio[951]: info: CorePlayQueue::getTrack 5 May 12 16:51:50 volumio volumio[951]: info: [1747083110567] ControllerPandora::ExpireOldTracks::reaper May 12 16:51:50 volumio volumio[951]: info: [1747083110567] [Pandora] ExpireOldTracks::reaper: No victims found: Expiring zero tracks. Don't worry -- Jason will return. May 12 16:51:57 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay May 12 16:51:57 volumio volumio[951]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom May 12 16:51:57 volumio volumio[951]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 16:51:57 volumio volumio[951]: TypeError: Cannot read property 'then' of undefined May 12 16:51:57 volumio volumio[951]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) May 12 16:51:57 volumio volumio[951]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) May 12 16:51:57 volumio volumio[951]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1456:26) May 12 16:51:57 volumio volumio[951]: at Socket.emit (events.js:315:20) May 12 16:51:57 volumio volumio[951]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 12 16:51:57 volumio volumio[951]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 12 16:51:57 volumio volumio[951]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 16:51:58 volumio sudo[8596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-12 16:50 May 12 16:51:58 volumio sudo[8596]: 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="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"