I have installed the Spotify plugin on the latest Volumio and managed to login to Spotify successfully. When I choose a playlist or track, it appears to play - the notification says it is playing and the progress bar moves, but no sound comes out. The second time I try, nothing at all happens.
Oh, could it be related to that? Yes I have it configured for software, although that is not working as I said here volume-control-problems-t13382.html. Most other things - local files, internet radio, all play fine, it’s just that I cannot control the volume, they are on ‘full’ all the time.
The v2.0.0 plugin is crashing the volumio service. Login is working but at authentification things go bad at the moment.
The spotify connect plugin v0.99 is working for me but sound quality is under the earth (dull).
Dec 03 09:10:52 volumio-mario sudo[24509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 03 09:10:52 volumio-mario sudo[24509]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 03 09:10:52 volumio-mario sudo[24509]: pam_unix(sudo:session): session closed for user root
Dec 03 09:10:53 volumio-mario volumio[15469]: 2019-12-03 09:10:53 [SPTF] 09:10:53.422 I [file_streamer_simple.cpp:769] Request for file a036063ef2b206a5a11b689951c521495a6bbf4e complete (code: 0)
Dec 03 09:10:53 volumio-mario volumio[15469]: 2019-12-03 09:10:53 [SPTF] 09:10:53.869 I [file_streamer_simple.cpp:769] Request for file a036063ef2b206a5a11b689951c521495a6bbf4e complete (code: 0)
Dec 03 09:10:55 volumio-mario volumio[900]: info: VolumeController::SetAlsaVolume47
Dec 03 09:10:55 volumio-mario volumio[900]: info: CoreStateMachine::pushState
Dec 03 09:10:55 volumio-mario volumio[900]: info: CoreStateMachine::getState
Dec 03 09:10:55 volumio-mario volumio[900]: info: CorePlayQueue::getTrack 24
Dec 03 09:10:55 volumio-mario volumio[900]: info: CoreCommandRouter::volumioPushState
Dec 03 09:10:55 volumio-mario volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 03 09:10:56 volumio-mario sudo[24527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 03 09:10:56 volumio-mario sudo[24527]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 03 09:10:56 volumio-mario sudo[24527]: pam_unix(sudo:session): session closed for user root
Dec 03 09:10:56 volumio-mario volumio[900]: info: VolumeController::SetAlsaVolume46
Dec 03 09:10:56 volumio-mario volumio[900]: info: CoreStateMachine::pushState
Dec 03 09:10:56 volumio-mario volumio[900]: info: CoreStateMachine::getState
Dec 03 09:10:56 volumio-mario volumio[900]: info: CorePlayQueue::getTrack 24
Dec 03 09:10:56 volumio-mario volumio[900]: info: CoreCommandRouter::volumioPushState
Dec 03 09:10:56 volumio-mario volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 03 09:10:56 volumio-mario sudo[24539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 03 09:10:56 volumio-mario sudo[24539]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 03 09:10:56 volumio-mario sudo[24539]: pam_unix(sudo:session): session closed for user root
Dec 03 09:10:57 volumio-mario volumio[900]: info: VolumeController::SetAlsaVolume44
Dec 03 09:10:57 volumio-mario volumio[900]: info: CoreStateMachine::pushState
Dec 03 09:10:57 volumio-mario volumio[900]: info: CoreStateMachine::getState
Dec 03 09:10:57 volumio-mario volumio[900]: info: CorePlayQueue::getTrack 24
Dec 03 09:10:57 volumio-mario volumio[900]: info: CoreCommandRouter::volumioPushState
Dec 03 09:10:57 volumio-mario volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 03 09:10:57 volumio-mario sudo[24551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 03 09:10:57 volumio-mario sudo[24551]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 03 09:10:57 volumio-mario sudo[24551]: pam_unix(sudo:session): session closed for user root
Dec 03 09:10:57 volumio-mario volumio[900]: info: VolumeController::SetAlsaVolume43
Dec 03 09:10:57 volumio-mario volumio[900]: info: CoreStateMachine::pushState
Dec 03 09:10:57 volumio-mario volumio[900]: info: CoreStateMachine::getState
Dec 03 09:10:57 volumio-mario volumio[900]: info: CorePlayQueue::getTrack 24
Dec 03 09:10:57 volumio-mario volumio[900]: info: CoreCommandRouter::volumioPushState
Dec 03 09:10:57 volumio-mario volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 03 09:10:57 volumio-mario sudo[24563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 03 09:10:57 volumio-mario sudo[24563]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 03 09:10:57 volumio-mario sudo[24563]: pam_unix(sudo:session): session closed for user root
Dec 03 09:11:02 volumio-mario nmbd[854]: [2019/12/03 09:11:02.967243, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
Dec 03 09:11:02 volumio-mario nmbd[854]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.5 for name WORKGROUP<1d>.
Dec 03 09:11:02 volumio-mario nmbd[854]: This response was from IP 192.168.1.12, reporting an IP address of 192.168.1.12.
Dec 03 09:11:14 volumio-mario volumio[900]: info: VolumeController::SetAlsaVolume38
Dec 03 09:11:14 volumio-mario volumio[900]: info: CoreStateMachine::pushState
Dec 03 09:11:14 volumio-mario volumio[900]: info: CoreStateMachine::getState
Dec 03 09:11:14 volumio-mario volumio[900]: info: CorePlayQueue::getTrack 24
Dec 03 09:11:14 volumio-mario volumio[900]: info: CoreCommandRouter::volumioPushState
Dec 03 09:11:14 volumio-mario volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 03 09:11:14 volumio-mario sudo[24610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 03 09:11:14 volumio-mario sudo[24610]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 03 09:11:14 volumio-mario sudo[24610]: pam_unix(sudo:session): session closed for user root
Dec 03 09:11:16 volumio-mario volumio[15469]: 2019-12-03 09:11:16 [SPTF] 09:11:16.378 I [file_streamer_simple.cpp:769] Request for file a036063ef2b206a5a11b689951c521495a6bbf4e complete (code: 0)
Dec 03 09:11:22 volumio-mario volumio[15469]: 2019-12-03 09:11:22 [SPTF] 09:11:22.477 I [offline-mgr:2056] 0 files unlocked. 0 images unlocked
Dec 03 09:11:41 volumio-mario volumio[15469]: 2019-12-03 09:11:41 [SPTF] 09:11:41.694 I [file_streamer_simple.cpp:769] Request for file a036063ef2b206a5a11b689951c521495a6bbf4e complete (code: 0)
Dec 03 09:12:08 volumio-mario volumio[15469]: 2019-12-03 09:12:08 [SPTF] 09:12:08.025 I [file_streamer_simple.cpp:769] Request for file a036063ef2b206a5a11b689951c521495a6bbf4e complete (code: 0)
Dec 03 09:12:34 volumio-mario volumio[15469]: 2019-12-03 09:12:34 [SPTF] 09:12:34.181 I [file_streamer_simple.cpp:769] Request for file a036063ef2b206a5a11b689951c521495a6bbf4e complete (code: 0)
Dec 03 09:13:00 volumio-mario volumio[15469]: 2019-12-03 09:13:00 [SPTF] 09:13:00.937 I [file_streamer_simple.cpp:769] Request for file a036063ef2b206a5a11b689951c521495a6bbf4e complete (code: 0)
Dec 03 09:13:28 volumio-mario volumio[15469]: 2019-12-03 09:13:28 [SPTF] 09:13:28.250 I [file_streamer_simple.cpp:769] Request for file a036063ef2b206a5a11b689951c521495a6bbf4e complete (code: 0)
Dec 03 09:13:55 volumio-mario volumio[15469]: 2019-12-03 09:13:55 [SPTF] 09:13:55.813 I [file_streamer_simple.cpp:769] Request for file a036063ef2b206a5a11b689951c521495a6bbf4e complete (code: 0)
Dec 03 09:14:55 volumio-mario volumio[900]: info: CorePlayQueue::getTrack 24
Dec 03 09:14:55 volumio-mario volumio[900]: info: CorePlayQueue::getTrack 25
Dec 03 09:15:00 volumio-mario volumio[15469]: 2019-12-03 09:15:00 [SPTF] 09:15:00.953 I [social_presence.cpp:135] Presence: A track was played
Dec 03 09:15:00 volumio-mario volumio[900]: info:
Dec 03 09:15:00 volumio-mario volumio[900]: [1575364500954] ---------------------------- Spop announces state update
Dec 03 09:15:00 volumio-mario volumio[900]: info: [1575364500955] ControllerSpop::parseState
Dec 03 09:15:00 volumio-mario volumio[900]: info: [1575364500956] ControllerSpop::pushState
Dec 03 09:15:00 volumio-mario volumio[900]: info: CoreCommandRouter::servicePushState
Dec 03 09:15:00 volumio-mario volumio[900]: info: CoreStateMachine::syncState
Dec 03 09:15:00 volumio-mario volumio[900]: info: CoreStateMachine::pushState
Dec 03 09:15:00 volumio-mario volumio[900]: info: CoreStateMachine::getState
Dec 03 09:15:00 volumio-mario volumio[900]: info: CorePlayQueue::getTrack 24
Dec 03 09:15:00 volumio-mario volumio[900]: info: CoreCommandRouter::volumioPushState
Dec 03 09:15:00 volumio-mario volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 03 09:15:00 volumio-mario volumio[900]: info: CorePlayQueue::getTrack 24
Dec 03 09:15:00 volumio-mario volumio[900]: info: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":"128Kbps","bitdepth":null,"channels":null}
Dec 03 09:15:00 volumio-mario volumio[900]: info: CURRENT POSITION 24
Dec 03 09:15:00 volumio-mario volumio[900]: info: CoreStateMachine::syncState stateService stop
Dec 03 09:15:00 volumio-mario volumio[900]: info: CoreStateMachine::syncState currentStatus play
Dec 03 09:15:00 volumio-mario volumio[900]: info: CURRENT POSITION 24
Dec 03 09:15:00 volumio-mario volumio[900]: info: CURRENT POSITION 25
Dec 03 09:15:00 volumio-mario volumio[900]: info: END OF QUEUE
Dec 03 09:15:00 volumio-mario volumio[900]: info: CoreStateMachine::pushEmptyState
Dec 03 09:15:00 volumio-mario volumio[900]: info: CoreCommandRouter::volumioPushState
Dec 03 09:15:00 volumio-mario volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 03 09:15:00 volumio-mario sudo[25162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 03 09:15:01 volumio-mario volumio[900]: info: CoreStateMachine::stPlaybackTimer
Dec 03 09:15:01 volumio-mario sudo[25162]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 03 09:15:01 volumio-mario volumio[900]: info: [1575364501010] ------------------------------ 56ms
Dec 03 09:15:01 volumio-mario sudo[25162]: pam_unix(sudo:session): session closed for user root
Dec 03 09:15:01 volumio-mario sudo[25166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 03 09:15:01 volumio-mario sudo[25166]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 03 09:15:01 volumio-mario sudo[25166]: pam_unix(sudo:session): session closed for user root
Dec 03 09:28:52 volumio-mario volumio[900]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri
Dec 03 09:28:52 volumio-mario volumio[900]: info: In handleBrowseUri, curUri=spotify
Dec 03 09:28:53 volumio-mario volumio[900]: (node:900) UnhandledPromiseRejectionWarning
Dec 03 09:28:53 volumio-mario volumio[900]: (node:900) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). (rejection id: 50)
Dec 03 09:28:53 volumio-mario volumio[900]: info: An error occurred while listing Spotify new albums WebapiError: Unauthorized
Dec 03 09:28:53 volumio-mario volumio[900]: info: An error occurred while listing Spotify featured playlists WebapiError: Unauthorized
Dec 03 09:28:53 volumio-mario volumio[900]: (node:900) UnhandledPromiseRejectionWarning
Dec 03 09:28:53 volumio-mario volumio[900]: (node:900) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). (rejection id: 51)
Dec 03 09:28:53 volumio-mario volumio[900]: (node:900) UnhandledPromiseRejectionWarning
Dec 03 09:28:53 volumio-mario volumio[900]: (node:900) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). (rejection id: 52)
Dec 03 09:28:53 volumio-mario volumio[900]: info: An error occurred while listing Spotify categories WebapiError: Unauthorized
at first: you must past here your log… nothing can be done without it.
second: I have the same problem as you and i past the log here, so we can only wait for the @Michelangelo
and to prove the point, Moode works, not just Spotify connect, but the volume control / mixer works, even hardware control, which has been missing since that update that broke it in Volumio quite a few versions ago.
A real pity as I am a fan of Volumio, and have been promoting it when asked for a recommendation for an rpi music system.
Software volume for spotify is on my todo list.
The thing about volume, unfortunately is something I coul not fully understand why it happened. The solution for that is unfortunately to perform a reset user data…