-- Logs begin at Wed 2024-04-03 07:57:42 UTC, end at Wed 2024-04-03 08:04:13 UTC. --
Apr 03 08:03:25 volumio-pc volumio[881]: info: Pushing metadata
Apr 03 08:03:25 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:25 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:25 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:25 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:03:25 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:25 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:25 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:25 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:03:26 volumio-pc volumio[881]: info: Pushing metadata
Apr 03 08:03:26 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:26 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:26 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:26 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:03:26 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:26 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:26 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:26 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:03:26 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:26 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:26 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:26 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:03:26 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:26 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:26 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:26 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:03:26 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:26 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:26 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:26 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:03:28 volumio-pc vtcs[1541]: VTCS: PCM: plughw:5,0 RATE: 44100 FORMAT: S16_LE CHANNELS: 2 PERIODSIZE: 4096 BUFFERSIZE: 32768
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:28 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:03:52 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:52 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:52 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:52 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:03:54 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:54 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:54 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:54 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:03:54 volumio-pc volumio[881]: info: Pushing metadata
Apr 03 08:03:54 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:54 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:54 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:54 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:03:54 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:54 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:54 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:54 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:03:54 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:54 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:54 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:54 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:03:55 volumio-pc volumio[881]: info: Pushing metadata
Apr 03 08:03:55 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:55 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:55 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:55 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:03:55 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:55 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:55 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:55 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:03:58 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:58 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:58 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:58 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:03:58 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:58 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:58 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:58 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:03:58 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:58 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:58 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:58 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:03:59 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:59 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:59 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:59 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:03:59 volumio-pc vtcs[1541]: VTCS: PCM: plughw:5,0 RATE: 44100 FORMAT: S16_LE CHANNELS: 2 PERIODSIZE: 4096 BUFFERSIZE: 32768
Apr 03 08:03:59 volumio-pc volumio[881]: info: CoreCommandRouter::servicePushState
Apr 03 08:03:59 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:03:59 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:03:59 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:04:12 volumio-pc volumio[1392]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443"
Apr 03 08:04:13 volumio-pc volumio[1392]: [Vollibrespot] : Authenticated as "1283195039" !
Apr 03 08:04:13 volumio-pc volumio[1392]: [Vollibrespot] : Setting up new mixer: card:hw:5 mixer:Speaker index:0
Apr 03 08:04:13 volumio-pc volumio[1392]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!!
Apr 03 08:04:13 volumio-pc volumio[1392]: [Vollibrespot] : Alsa Mixer info min: 0 (MilliBel(-10000)[dB]) -- max: 100 (MilliBel(0)[dB]) HW: true
Apr 03 08:04:13 volumio-pc volumio[1392]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!!
Apr 03 08:04:13 volumio-pc volumio[1392]: [Vollibrespot] : Using alsa sink
Apr 03 08:04:13 volumio-pc volumio[1392]: [Vollibrespot] : Metadata pipe established
Apr 03 08:04:13 volumio-pc volumio[1392]: [Vollibrespot] : Country: "MY"
Apr 03 08:04:13 volumio-pc volumio[1392]: [Vollibrespot] : Event: Volume { volume_to_mixer: 65535 }
Apr 03 08:04:13 volumio-pc volumio[881]: [SpotifyConnect] 100
Apr 03 08:04:13 volumio-pc volumio[881]: [SpotifyConnect] Volume: Spotify:100 Volumio: 100
Apr 03 08:04:13 volumio-pc volumio[881]: info: CoreStateMachine::pushState
Apr 03 08:04:13 volumio-pc volumio[881]: info: CoreCommandRouter::volumioPushState
Apr 03 08:04:13 volumio-pc volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 08:04:13 volumio-pc volumio[1392]: [Vollibrespot] : Fetching autoplay context uri
Apr 03 08:04:13 volumio-pc volumio[1392]: [Vollibrespot] : Event: SessionActive { became_active_at: 1712131453554 }
Apr 03 08:04:13 volumio-pc volumio[1392]: [Vollibrespot] : SessionActive!
Apr 03 08:04:13 volumio-pc volumio[1392]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 207858411399009966835664353906695538238, audio_type: Track } }
Apr 03 08:04:13 volumio-pc volumio[1392]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 207858411399009966835664353906695538238, audio_type: Track } }
Apr 03 08:04:13 volumio-pc volumio[881]: [SpotifyConnect] A connect session has begun
Apr 03 08:04:13 volumio-pc volumio[881]: [SpotifyConnect] Device palyback is active!
Apr 03 08:04:13 volumio-pc volumio[881]: info: CoreCommandRouter::volumioGetState
Apr 03 08:04:13 volumio-pc volumio[881]: [SpotifyConnect] Currently active: tidalconnect
Apr 03 08:04:13 volumio-pc volumio[881]: [SpotifyConnect] Stopping currently active service
Apr 03 08:04:13 volumio-pc volumio[881]: info: CoreCommandRouter::volumioStop
Apr 03 08:04:13 volumio-pc volumio[881]: info: CoreStateMachine::stop
Apr 03 08:04:13 volumio-pc volumio[881]: info: CoreStateMachine::serviceStop
Apr 03 08:04:13 volumio-pc volumio[881]: info: CoreCommandRouter::serviceStop
Apr 03 08:04:13 volumio-pc volumio[881]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 03 08:04:13 volumio-pc volumio[881]: TypeError: Cannot read property 'then' of undefined
Apr 03 08:04:13 volumio-pc volumio[881]: at SpotConnEvents.SpotConn.on (/data/plugins/music_service/volspotconnect2/index.js:143:23)
Apr 03 08:04:13 volumio-pc volumio[881]: at emitNone (events.js:106:13)
Apr 03 08:04:13 volumio-pc volumio[881]: at SpotConnEvents.emit (events.js:208:7)
Apr 03 08:04:13 volumio-pc volumio[881]: at SpotConnEvents.parseData (/data/plugins/music_service/volspotconnect2/SpotConnController.js:73:16)
Apr 03 08:04:13 volumio-pc volumio[881]: at Socket.SpotConnEvents._udpsource.on.msg (/data/plugins/music_service/volspotconnect2/SpotConnController.js:15:12)
Apr 03 08:04:13 volumio-pc volumio[881]: at emitTwo (events.js:126:13)
Apr 03 08:04:13 volumio-pc volumio[881]: at Socket.emit (events.js:214:7)
Apr 03 08:04:13 volumio-pc volumio[881]: at UDP.onMessage [as onmessage] (dgram.js:659:8)
Apr 03 08:04:13 volumio-pc volumio[881]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 03 08:04:13 volumio-pc vtcs[1541]: [2024-04-03 08:04:13] [info] asio async_shutdown error: asio.misc:2 (End of file)
Apr 03 08:04:13 volumio-pc vtcs[1541]: [2024-04-03 08:04:13.578] [tisoc] [warning] [PlaybackControllerImpl.cpp:472] Illegal state:0
Apr 03 08:04:13 volumio-pc volumio[1392]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:5XqVUg5oGiInNaArBOr8Vz">
Apr 03 08:04:13 volumio-pc volumio[1392]: [Vollibrespot] : Resolving uri "spotify:station:playlist:5XqVUg5oGiInNaArBOr8Vz"
Apr 03 08:04:13 volumio-pc volumio[1392]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQC6n3aLCcgsRA5Or8fn6tCBHEJK-7TMyAhT96fTI-Y4WADainKtTtwLXG-eEQR4DoPbO0xCmgL1yUZ-dLdJCpnDLSyHvK3Hqpt0nMkZmJjAg-xx0JErYvrE2UOXdxesVhkY5pMDrRZ7z3xSMe67v4GkxqEyYIj0hTa8GKqzmGW_SxGiMnvk_Q6yoAd3gEIAWZyCfw", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } }
Apr 03 08:04:13 volumio-pc sudo[2584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-03 08:03
Apr 03 08:04:13 volumio-pc sudo[2584]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Debian GNU/Linux 8 (jessie)"
NAME="Debian GNU/Linux"
VERSION_ID="8"
VERSION="8 (jessie)"
ID=debian
HOME_URL="http://www.debian.org/"
SUPPORT_URL="http://www.debian.org/support"
BUG_REPORT_URL="https://bugs.debian.org/"
VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03"
VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae"
VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d"
VOLUMIO_ARCH="x86"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Fri Oct 1 11:49:13 CEST 2021"
VOLUMIO_VERSION="2.916"
VOLUMIO_HARDWARE="x86"
VOLUMIO_HASH="62511ec23417b6085328d5bda7373a0c"