-- Logs begin at Thu 2025-02-13 04:22:29 UTC, end at Sun 2025-03-02 17:12:28 UTC. -- Mar 02 17:11:05 BigBadWoof ntpd[756]: Soliciting pool server 23.155.40.38 Mar 02 17:11:34 BigBadWoof ntpd[756]: Soliciting pool server 23.142.248.9 Mar 02 17:11:43 BigBadWoof ntpd[756]: Soliciting pool server 129.250.35.251 Mar 02 17:12:01 BigBadWoof ntpd[756]: Soliciting pool server 50.205.57.38 Mar 02 17:12:12 BigBadWoof ntpd[756]: Soliciting pool server 45.33.53.84 Mar 02 17:12:24 BigBadWoof volumio[1377]: INFO:librespot_core::session: Connecting to AP "ap-gue1.spotify.com:4070" Mar 02 17:12:24 BigBadWoof volumio[1377]: INFO:librespot_core::session: Authenticated as "31xkax3hbu3t5x4jb2zjmqxi7nzy" ! Mar 02 17:12:24 BigBadWoof volumio[1377]: INFO:librespot_playback::player: new Player[0] Mar 02 17:12:24 BigBadWoof volumio[1377]: INFO:librespot_playback::audio_backend::alsa: Using alsa sink Mar 02 17:12:24 BigBadWoof volumio[1377]: INFO:librespot_core::session: Country: "US" Mar 02 17:12:24 BigBadWoof volumio[502]: info: VolumeController::SetAlsaVolume55704 Mar 02 17:12:24 BigBadWoof volumio[502]: amixer -M set -c 0 "Digital" 84.99885557335774% Mar 02 17:12:24 BigBadWoof volumio[502]: null Mar 02 17:12:24 BigBadWoof volumio[502]: info: VolumeController::Volume 84.99885557335774 Mar 02 17:12:24 BigBadWoof volumio[502]: info: CoreStateMachine::pushState Mar 02 17:12:24 BigBadWoof volumio[502]: info: CoreStateMachine::getState Mar 02 17:12:24 BigBadWoof volumio[502]: info: CorePlayQueue::getTrack 0 Mar 02 17:12:24 BigBadWoof volumio[502]: info: CoreCommandRouter::volumioPushState Mar 02 17:12:24 BigBadWoof volumio[502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:12:24 BigBadWoof volumio[502]: info: VOLUMIO PUSHED THIS STATE: status - stop, service - webradio Mar 02 17:12:24 BigBadWoof volumio[502]: info: interfaceApi::pushState Mar 02 17:12:24 BigBadWoof volumio[502]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Mar 02 17:12:24 BigBadWoof volumio[502]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Mar 02 17:12:24 BigBadWoof volumio[502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Mar 02 17:12:24 BigBadWoof sudo[16687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 02 17:12:24 BigBadWoof sudo[16687]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 17:12:24 BigBadWoof sudo[16687]: pam_unix(sudo:session): session closed for user root Mar 02 17:12:24 BigBadWoof volumio[502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Mar 02 17:12:24 BigBadWoof sudo[16703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 02 17:12:24 BigBadWoof sudo[16703]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 17:12:24 BigBadWoof sudo[16703]: pam_unix(sudo:session): session closed for user root Mar 02 17:12:24 BigBadWoof volumio[502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:12:24 BigBadWoof volumio[502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Mar 02 17:12:24 BigBadWoof sudo[16721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 02 17:12:24 BigBadWoof sudo[16721]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 17:12:24 BigBadWoof sudo[16721]: pam_unix(sudo:session): session closed for user root Mar 02 17:12:24 BigBadWoof sudo[16738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 02 17:12:24 BigBadWoof sudo[16738]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 17:12:24 BigBadWoof volumio[502]: info: Pushing Favourites {"service":"webradio","uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996","favourite":false} Mar 02 17:12:24 BigBadWoof sudo[16738]: pam_unix(sudo:session): session closed for user root Mar 02 17:12:25 BigBadWoof volumio[1377]: INFO:librespot_playback::player: SpCon::kSpPlaybackNotifyBecameActive Mar 02 17:12:25 BigBadWoof volumio[1377]: INFO:librespot_playback::player: SpCon::token Mar 02 17:12:25 BigBadWoof volumio[502]: info: [SpConDebug] Session is active! Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreCommandRouter::volumioGetState Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreStateMachine::getState Mar 02 17:12:25 BigBadWoof volumio[502]: info: CorePlayQueue::getTrack 0 Mar 02 17:12:25 BigBadWoof volumio[502]: info: [SpConDebug] Currently active:webradio Mar 02 17:12:25 BigBadWoof volumio[502]: info: [SpConDebug] Stopping currently active service Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreCommandRouter::volumioStop Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreStateMachine::getState Mar 02 17:12:25 BigBadWoof volumio[502]: info: CorePlayQueue::getTrack 0 Mar 02 17:12:25 BigBadWoof volumio[502]: info: VOLUMIOSTOP: service stopped - webradio Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreStateMachine::stop Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:12:25 BigBadWoof volumio[502]: UNSET VOLATILE Mar 02 17:12:25 BigBadWoof volumio[502]: info: [SpConDebug] SpotConn Active Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreCommandRouter::volumioGetState Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreStateMachine::getState Mar 02 17:12:25 BigBadWoof volumio[502]: info: CorePlayQueue::getTrack 0 Mar 02 17:12:25 BigBadWoof volumio[502]: info: [SpConDebug] Currently active:webradio Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreCommandRouter::servicePushState Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreStateMachine::syncState Mar 02 17:12:25 BigBadWoof volumio[502]: info: CorePlayQueue::getTrack 0 Mar 02 17:12:25 BigBadWoof volumio[502]: info: STATE SERVICE {"status":"pause","service":"volspotconnect2","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"channels":2,"streaming":true} Mar 02 17:12:25 BigBadWoof volumio[502]: info: CURRENT POSITION 0 Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreStateMachine::syncState stateService pause Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreStateMachine::syncState currentStatus stop Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreStateMachine::pushState Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreStateMachine::getState Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreCommandRouter::volumioPushState Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:12:25 BigBadWoof volumio[502]: info: VOLUMIO PUSHED THIS STATE: status - play, service - airplay Mar 02 17:12:25 BigBadWoof volumio[502]: info: interfaceApi::pushState Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Mar 02 17:12:25 BigBadWoof sudo[16876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 02 17:12:25 BigBadWoof sudo[16876]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 17:12:25 BigBadWoof sudo[16876]: pam_unix(sudo:session): session closed for user root Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Mar 02 17:12:25 BigBadWoof sudo[16888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 02 17:12:25 BigBadWoof sudo[16888]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Mar 02 17:12:25 BigBadWoof sudo[16888]: pam_unix(sudo:session): session closed for user root Mar 02 17:12:25 BigBadWoof sudo[16902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 02 17:12:25 BigBadWoof sudo[16902]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 17:12:25 BigBadWoof sudo[16902]: pam_unix(sudo:session): session closed for user root Mar 02 17:12:25 BigBadWoof sudo[16926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 02 17:12:25 BigBadWoof sudo[16926]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 17:12:25 BigBadWoof volumio[502]: info: [SpConDebug] Token: BQDAlKHCheDk-JOwMqBhDyBom5J-a5Gdcpd5ubuWCLyP0oUvB50Hm1dOqk1ZiUL4Q71sLoQl9U2rq0d85Ot94caE_aMRSbcRL_NKUdLxWcpi1pJLKBm_5pGgZW-K1j7YfETt8nKajrcMtOdDJUdmOoJFEQ7GywffoyzeA3NqosfsMPRC9K0GO0lVRFd364_K3yhXPP9MLc_LlKnLnq01XiMyqcA5_1boVqmtapD-EEdEYzQd_hOlLmIz9nak8JBvvNWwsg Mar 02 17:12:25 BigBadWoof sudo[16926]: pam_unix(sudo:session): session closed for user root Mar 02 17:12:25 BigBadWoof volumio[1274]: Signal Mar 02 17:12:25 BigBadWoof volumio[502]: info: Spotify running. Killing Airplay Mar 02 17:12:25 BigBadWoof volumio[502]: info: Pushing Favourites {"service":"airplay","uri":"","favourite":false} Mar 02 17:12:25 BigBadWoof volumio[1274]: 2025-03-02 05:12:25.539805 PM [AirPlay] Deregistered Bonjour _airplay._tcp. for stop Mar 02 17:12:25 BigBadWoof volumio[1274]: 2025-03-02 05:12:25.541006 PM [AirPlay] Deregistered Bonjour _raop._tcp. for stop Mar 02 17:12:25 BigBadWoof volumio[1274]: 2025-03-02 05:12:25.542405 PM [AirPlay] AirPlay servers stopped Mar 02 17:12:25 BigBadWoof volumio[1274]: Caught signal 1, restarting. Mar 02 17:12:25 BigBadWoof volumio[1274]: airplayd v. 1.3.10 Mar 02 17:12:25 BigBadWoof volumio[1274]: pid: 1274 Mar 02 17:12:25 BigBadWoof volumio[1274]: 2025-03-02 05:12:25.545864 PM [AirPlay] ### AirPlay Audio Receiver POSIX Release Version: 211.1.p8 Mar 02 17:12:25 BigBadWoof volumio[1274]: 2025-03-02 05:12:25.546399 PM [AirPlay] Name changed '' -> 'BigBadWoof' Mar 02 17:12:25 BigBadWoof volumio[1274]: 2025-03-02 05:12:25.567741 PM [AirPlay] Registering Bonjour _airplay._tcp. port 5000 Mar 02 17:12:25 BigBadWoof volumio[1274]: 2025-03-02 05:12:25.584453 PM [AirPlay] Registering Bonjour B827EBB2B65C@BigBadWoof._raop._tcp. port 5000 Mar 02 17:12:25 BigBadWoof volumio[1274]: 2025-03-02 05:12:25.584590 PM [AirPlay] AirPlay servers started Mar 02 17:12:25 BigBadWoof volumio[1377]: INFO:librespot_playback::player: SpCon::metadata Mar 02 17:12:25 BigBadWoof volumio[502]: info: [SpConDebug] Pushing metadata::Vollibrespot:true Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreCommandRouter::servicePushState Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreStateMachine::syncState Mar 02 17:12:25 BigBadWoof volumio[502]: info: CorePlayQueue::getTrack 0 Mar 02 17:12:25 BigBadWoof volumio[502]: info: STATE SERVICE {"status":"pause","service":"volspotconnect2","title":"All Right Now","artist":"Free","album":"Fire And Water","albumart":"https://i.scdn.co/image/ab67616d0000b273753c41c7fdc5e78ba017bbf5","uri":"spotify:track:2971ba616cd04197a29378d762d91610","trackType":"spotify","seek":45407,"duration":331,"channels":2,"streaming":true} Mar 02 17:12:25 BigBadWoof volumio[502]: info: CURRENT POSITION 0 Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreStateMachine::syncState stateService pause Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreStateMachine::syncState currentStatus stop Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreStateMachine::pushState Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreStateMachine::getState Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreCommandRouter::volumioPushState Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:12:25 BigBadWoof volumio[502]: info: VOLUMIO PUSHED THIS STATE: status - play, service - airplay Mar 02 17:12:25 BigBadWoof volumio[502]: info: interfaceApi::pushState Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Mar 02 17:12:25 BigBadWoof volumio[1377]: INFO:librespot_playback::player: Loading track "All Right Now" with Spotify URI "spotify:track:1gcESexgftSuLuML57Y69q" Mar 02 17:12:25 BigBadWoof sudo[16989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 02 17:12:25 BigBadWoof sudo[16989]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Mar 02 17:12:25 BigBadWoof sudo[16989]: pam_unix(sudo:session): session closed for user root Mar 02 17:12:25 BigBadWoof sudo[16999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 02 17:12:25 BigBadWoof sudo[16999]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:12:25 BigBadWoof volumio[502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Mar 02 17:12:25 BigBadWoof sudo[16999]: pam_unix(sudo:session): session closed for user root Mar 02 17:12:25 BigBadWoof sudo[17013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 02 17:12:25 BigBadWoof sudo[17013]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 17:12:25 BigBadWoof sudo[17013]: pam_unix(sudo:session): session closed for user root Mar 02 17:12:25 BigBadWoof sudo[17026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 02 17:12:25 BigBadWoof sudo[17026]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 17:12:25 BigBadWoof sudo[17026]: pam_unix(sudo:session): session closed for user root Mar 02 17:12:25 BigBadWoof volumio[502]: info: Pushing Favourites {"service":"airplay","uri":"","favourite":false} Mar 02 17:12:27 BigBadWoof volumio[1377]: INFO:librespot_playback::player: Track "All Right Now" loaded Mar 02 17:12:27 BigBadWoof volumio[1377]: INFO:librespot_playback::player: SpCon::kSpDeviceActive Mar 02 17:12:27 BigBadWoof volumio[502]: info: [SpConDebug] Device is active! Mar 02 17:12:27 BigBadWoof volumio[502]: info: CoreCommandRouter::volumioGetState Mar 02 17:12:27 BigBadWoof volumio[502]: info: CoreStateMachine::getState Mar 02 17:12:27 BigBadWoof volumio[502]: info: [SpConDebug] Currently active:airplay Mar 02 17:12:27 BigBadWoof volumio[502]: info: [SpConDebug] Stopping currently active service Mar 02 17:12:27 BigBadWoof volumio[502]: info: CoreCommandRouter::volumioStop Mar 02 17:12:27 BigBadWoof volumio[502]: info: CoreStateMachine::getState Mar 02 17:12:27 BigBadWoof volumio[502]: info: VOLUMIOSTOP: service stopped - airplay Mar 02 17:12:27 BigBadWoof volumio[502]: info: CoreStateMachine::stop Mar 02 17:12:27 BigBadWoof volumio[502]: info: CoreStateMachine::serviceStop Mar 02 17:12:27 BigBadWoof volumio[502]: info: Received STOP, but no service to execute it Mar 02 17:12:27 BigBadWoof kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Mar 02 17:12:27 BigBadWoof volumio[502]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 02 17:12:27 BigBadWoof volumio[502]: TypeError: Cannot read property 'then' of undefined Mar 02 17:12:27 BigBadWoof volumio[502]: at SpotConnEvents. (/volumio/app/plugins/music_service/volspotconnect2/index.js:163:21) Mar 02 17:12:27 BigBadWoof volumio[502]: at emitOne (events.js:116:13) Mar 02 17:12:27 BigBadWoof volumio[502]: at SpotConnEvents.emit (events.js:211:7) Mar 02 17:12:27 BigBadWoof volumio[502]: at SpotConnEvents.parseData (/volumio/app/plugins/music_service/volspotconnect2/SpotConnController.js:43:16) Mar 02 17:12:27 BigBadWoof volumio[502]: at Socket.SpotConnEvents.self._udpsource.on.msg (/volumio/app/plugins/music_service/volspotconnect2/SpotConnController.js:16:12) Mar 02 17:12:27 BigBadWoof volumio[502]: at emitTwo (events.js:126:13) Mar 02 17:12:27 BigBadWoof volumio[502]: at Socket.emit (events.js:214:7) Mar 02 17:12:27 BigBadWoof volumio[502]: at UDP.onMessage [as onmessage] (dgram.js:659:8) Mar 02 17:12:27 BigBadWoof volumio[502]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 02 17:12:27 BigBadWoof volumio[1377]: INFO:librespot_playback::player: SpCon::kSpSinkActive Mar 02 17:12:28 BigBadWoof sudo[17598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-02 17:11 Mar 02 17:12:28 BigBadWoof sudo[17598]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 9 (stretch)" NAME="Raspbian GNU/Linux" VERSION_ID="9" VERSION="9 (stretch)a" 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_FE_VERSION="01dba59212a3d6229a00a84a6bba61a7927a0d44" VOLUMIO_BE_VERSION="acc7fd04890fcc10134578c1415f4bd9126db8db" VOLUMIO_ARCH="arm" VERSION="V2.09a" VOLUMIO_VERSION="V2.09 - Toasted Walnut" VOLUMIO_VARIANT="AxiomAirV2" VOLUMIO_HARDWARE="AxiomAirV2" VOLUMIO_BUILD_DATE="2018-12-18"