-- Logs begin at Thu 2019-02-14 11:11:59 +01, end at Sun 2025-01-19 00:29:05 +01. -- Jan 19 00:28:04 szympek sudo[6567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 19 00:28:04 szympek sudo[6567]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 00:28:04 szympek sudo[6567]: pam_unix(sudo:session): session closed for user root Jan 19 00:28:04 szympek sudo[6570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 19 00:28:04 szympek sudo[6570]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 00:28:04 szympek sudo[6570]: pam_unix(sudo:session): session closed for user root Jan 19 00:28:05 szympek volumio[1132]: verbose: New Socket.io Connection to 192.168.0.107 from 192.168.0.182 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0 Engine version: 3 Transport: polling Total Clients: 5 Jan 19 00:28:05 szympek ntpd[788]: 89.25.168.148 local addr 192.168.0.107 -> Jan 19 00:28:05 szympek sudo[6573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 19 00:28:05 szympek sudo[6573]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 00:28:05 szympek sudo[6573]: pam_unix(sudo:session): session closed for user root Jan 19 00:28:05 szympek sudo[6576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 19 00:28:05 szympek sudo[6576]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 00:28:06 szympek sudo[6576]: pam_unix(sudo:session): session closed for user root Jan 19 00:28:06 szympek volumio[1132]: verbose: New Socket.io Connection to 192.168.0.107 from 192.168.0.182 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0 Engine version: 3 Transport: polling Total Clients: 5 Jan 19 00:28:06 szympek volumio[1132]: info: CoreCommandRouter::volumioGetState Jan 19 00:28:06 szympek volumio[1132]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 19 00:28:06 szympek volumio[1132]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 19 00:28:06 szympek volumio[1132]: info: Listing playlists Jan 19 00:28:06 szympek volumio[1132]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 19 00:28:06 szympek volumio[1132]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 19 00:28:06 szympek volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 19 00:28:06 szympek volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 19 00:28:07 szympek volumio[1132]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 19 00:28:07 szympek volumio[1132]: info: Received Get System Info Jan 19 00:28:07 szympek volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 00:28:07 szympek volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 00:28:07 szympek volumio[1132]: info: Discovery: Getting this device information Jan 19 00:28:07 szympek volumio[1132]: info: CoreCommandRouter::volumioGetState Jan 19 00:28:07 szympek volumio[1132]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 00:28:09 szympek volumio[1132]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 19 00:28:09 szympek volumio[1132]: info: Received Get System Info Jan 19 00:28:09 szympek volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 00:28:09 szympek volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 00:28:09 szympek volumio[1132]: info: Discovery: Getting this device information Jan 19 00:28:09 szympek volumio[1132]: info: CoreCommandRouter::volumioGetState Jan 19 00:28:09 szympek volumio[1132]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 00:28:12 szympek volumio[1132]: info: CoreCommandRouter::volumioVolatilePlay Jan 19 00:28:12 szympek volumio[1132]: info: CoreStateMachine::volatilePlay Jan 19 00:28:12 szympek volumio[1132]: info: Received play: Jan 19 00:28:12 szympek volumio[1132]: info: CoreCommandRouter::servicePushState Jan 19 00:28:12 szympek volumio[1132]: info: CoreStateMachine::pushState Jan 19 00:28:12 szympek volumio[1132]: info: CoreCommandRouter::volumioPushState Jan 19 00:28:12 szympek volumio[1132]: info: MRS: Pushing multiroomSync output update for this device Jan 19 00:28:12 szympek volumio[1132]: info: MRS: Pushing multiroomSync output Jan 19 00:28:12 szympek volumio[1132]: info: CoreCommandRouter::servicePushState Jan 19 00:28:12 szympek volumio[1132]: info: CoreStateMachine::pushState Jan 19 00:28:12 szympek volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 00:28:12 szympek volumio[1132]: info: CoreCommandRouter::volumioPushState Jan 19 00:28:12 szympek volumio[1132]: info: MRS: Pushing multiroomSync output update for this device Jan 19 00:28:12 szympek volumio[1132]: info: MRS: Pushing multiroomSync output Jan 19 00:28:12 szympek volumio[1132]: info: CoreCommandRouter::servicePushState Jan 19 00:28:12 szympek volumio[1132]: info: CoreStateMachine::pushState Jan 19 00:28:12 szympek volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 00:28:12 szympek volumio[1132]: info: CoreCommandRouter::volumioPushState Jan 19 00:28:12 szympek volumio[1132]: info: MRS: Pushing multiroomSync output update for this device Jan 19 00:28:12 szympek volumio[1132]: info: MRS: Pushing multiroomSync output Jan 19 00:28:18 szympek kernel: hwmon hwmon1: Undervoltage detected! Jan 19 00:28:24 szympek volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 19 00:28:24 szympek volumio[1132]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 19 00:28:24 szympek volumio[1132]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 19 00:28:24 szympek volumio[1132]: info: Received Get System Version Jan 19 00:28:24 szympek volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 19 00:28:24 szympek volumio[1132]: info: Received Get System Info Jan 19 00:28:24 szympek volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 00:28:24 szympek volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 00:28:24 szympek volumio[1132]: info: Discovery: Getting this device information Jan 19 00:28:24 szympek volumio[1132]: info: CoreCommandRouter::volumioGetState Jan 19 00:28:24 szympek volumio[1132]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 00:28:25 szympek kernel: hwmon hwmon1: Voltage normalised Jan 19 00:28:49 szympek volumiossh-tunnel[1600]: Connection to eu9.myvolumio.org closed by remote host. Jan 19 00:28:49 szympek autossh[1604]: ssh exited with error status 255; restarting ssh Jan 19 00:28:49 szympek autossh[1604]: starting ssh (count 2) Jan 19 00:28:49 szympek autossh[1604]: ssh child pid is 6702 Jan 19 00:28:49 szympek volumiossh-tunnel[1600]: ssh: connect to host eu9.myvolumio.org port 2222: Connection refused Jan 19 00:28:49 szympek autossh[1604]: ssh exited with error status 255; restarting ssh Jan 19 00:28:49 szympek autossh[1604]: starting ssh (count 3) Jan 19 00:28:49 szympek autossh[1604]: ssh child pid is 6703 Jan 19 00:28:49 szympek volumiossh-tunnel[1600]: ssh: connect to host eu9.myvolumio.org port 2222: Connection refused Jan 19 00:28:49 szympek autossh[1604]: ssh exited with error status 255; restarting ssh Jan 19 00:28:49 szympek autossh[1604]: starting ssh (count 4) Jan 19 00:28:49 szympek autossh[1604]: ssh child pid is 6704 Jan 19 00:28:49 szympek volumiossh-tunnel[1600]: ssh: connect to host eu9.myvolumio.org port 2222: Connection refused Jan 19 00:28:49 szympek autossh[1604]: ssh exited with error status 255; restarting ssh Jan 19 00:28:49 szympek autossh[1604]: starting ssh (count 5) Jan 19 00:28:49 szympek autossh[1604]: ssh child pid is 6705 Jan 19 00:28:49 szympek volumiossh-tunnel[1600]: ssh: connect to host eu9.myvolumio.org port 2222: Connection refused Jan 19 00:28:49 szympek autossh[1604]: ssh exited with error status 255; restarting ssh Jan 19 00:28:49 szympek autossh[1604]: starting ssh (count 6) Jan 19 00:28:49 szympek autossh[1604]: ssh child pid is 6706 Jan 19 00:28:50 szympek volumiossh-tunnel[1600]: Warning: Permanently added '[eu9.myvolumio.org]:2222,[164.92.213.40]:2222' (RSA) to the list of known hosts. Jan 19 00:29:04 szympek volumio[6529]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Jan 19 00:29:04 szympek volumio[6529]: [Vollibrespot] : Authenticated as "szym50turbo" ! Jan 19 00:29:04 szympek volumio[6529]: [Vollibrespot] : Setting up new mixer: card:hw:2 mixer:SoftMaster index:0 Jan 19 00:29:04 szympek volumio[6529]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! Jan 19 00:29:04 szympek volumio[6529]: [Vollibrespot] : Alsa Mixer info min: 0 (MilliBel(0)[dB]) -- max: 99 (MilliBel(0)[dB]) HW: false Jan 19 00:29:04 szympek volumio[6529]: [Vollibrespot] : Using Alsa linear volume Jan 19 00:29:04 szympek volumio[6529]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! Jan 19 00:29:04 szympek volumio[6529]: [Vollibrespot] : Using alsa sink Jan 19 00:29:04 szympek volumio[6529]: [Vollibrespot] : Metadata pipe established Jan 19 00:29:04 szympek volumio[6529]: [Vollibrespot] : Event: Volume { volume_to_mixer: 0 } Jan 19 00:29:04 szympek volumio[1132]: [SpotifyConnect] 0 Jan 19 00:29:04 szympek volumio[1132]: [SpotifyConnect] Volume: Spotify:0 Volumio: 0 Jan 19 00:29:04 szympek volumio[1132]: info: CoreStateMachine::pushState Jan 19 00:29:04 szympek volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 00:29:04 szympek volumio[1132]: info: CoreCommandRouter::volumioPushState Jan 19 00:29:04 szympek volumio[6529]: [Vollibrespot] : Country: "PL" Jan 19 00:29:04 szympek volumio[1132]: info: MRS: Pushing multiroomSync output update for this device Jan 19 00:29:04 szympek volumio[1132]: info: MRS: Pushing multiroomSync output Jan 19 00:29:05 szympek volumio[6529]: [Vollibrespot] : Fetching autoplay context uri Jan 19 00:29:05 szympek volumio[6529]: [Vollibrespot] : Event: SessionActive { became_active_at: 1737242945121 } Jan 19 00:29:05 szympek volumio[6529]: [Vollibrespot] : SessionActive! Jan 19 00:29:05 szympek volumio[6529]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 190850658272225767456125681351314766408, audio_type: Track } } Jan 19 00:29:05 szympek volumio[6529]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 190850658272225767456125681351314766408, audio_type: Track } } Jan 19 00:29:05 szympek volumio[1132]: [SpotifyConnect] A connect session has begun Jan 19 00:29:05 szympek volumio[1132]: [SpotifyConnect] Device palyback is active! Jan 19 00:29:05 szympek volumio[1132]: info: CoreCommandRouter::volumioGetState Jan 19 00:29:05 szympek volumio[1132]: [SpotifyConnect] Currently active: tidalconnect Jan 19 00:29:05 szympek volumio[1132]: [SpotifyConnect] Stopping currently active service Jan 19 00:29:05 szympek volumio[1132]: info: CoreCommandRouter::volumioStop Jan 19 00:29:05 szympek volumio[1132]: info: CoreStateMachine::stop Jan 19 00:29:05 szympek volumio[1132]: info: CoreStateMachine::serviceStop Jan 19 00:29:05 szympek volumio[1132]: info: CoreCommandRouter::serviceStop Jan 19 00:29:05 szympek volumio[1132]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 00:29:05 szympek vtcs[1650]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:935:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot advance its hw pointer as the target pcm softvolume is in state SETUP. Jan 19 00:29:05 szympek volumio[1132]: TypeError: Cannot read property 'then' of undefined Jan 19 00:29:05 szympek volumio[1132]: at SpotConnEvents. (/data/plugins/music_service/volspotconnect2/index.js:143:23) Jan 19 00:29:05 szympek volumio[1132]: at SpotConnEvents.emit (events.js:315:20) Jan 19 00:29:05 szympek volumio[1132]: at SpotConnEvents.parseData (/data/plugins/music_service/volspotconnect2/SpotConnController.js:73:16) Jan 19 00:29:05 szympek volumio[1132]: at Socket. (/data/plugins/music_service/volspotconnect2/SpotConnController.js:15:12) Jan 19 00:29:05 szympek volumio[1132]: at Socket.emit (events.js:315:20) Jan 19 00:29:05 szympek volumio[1132]: at UDP.onMessage [as onmessage] (dgram.js:919:8) Jan 19 00:29:05 szympek volumio[1132]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 00:29:05 szympek vtcs[1650]: [2025-01-19 00:29:05] [info] asio async_shutdown error: asio.misc:2 (End of file) Jan 19 00:29:05 szympek volumio[6529]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:37i9dQZF1E36tRcGmvLpGk"> Jan 19 00:29:05 szympek volumio[6529]: [Vollibrespot] : Resolving uri "spotify:station:playlist:37i9dQZF1E36tRcGmvLpGk" Jan 19 00:29:05 szympek volumio[6529]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQDovwFKsdYONPdM3HtGq5pqYs2jkBOhsO-YOBcmDdvcvwVZ_br15wR_Uy1oRGbTeAo-irir_CcO0L62YlNZM7H_STJ1MLn6xrvA4iNfIZRO5pzg4B_eYn_3sdbQEme4bAt2KXJIg4z1iUlMBHIVH9ryZbLCF9cKciReZ9S0nFskFL7UvFHSCa8FV9rVgmEpAPdpgpCt-Qg80rM", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Jan 19 00:29:05 szympek volumio[6529]: [Vollibrespot] : Loading with Spotify URI Jan 19 00:29:05 szympek volumio[6529]: [Vollibrespot] : Resolved 50 tracks from <"spotify:user:szym50turbo:collection"> Jan 19 00:29:05 szympek sudo[6745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-19 00:28 Jan 19 00:29:05 szympek sudo[6745]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"