-- Logs begin at Wed 2024-07-24 16:52:54 UTC, end at Wed 2024-07-24 17:06:37 UTC. --
Jul 24 17:05:50 volumio-small volumio[740]: info: CorePlayQueue::getTrack 1
Jul 24 17:05:50 volumio-small volumio[740]: info: CorePlayQueue::getTrack 2
Jul 24 17:05:50 volumio-small volumio[740]: info: Prefetching next song
Jul 24 17:05:50 volumio-small volumio[740]: info: DOING PREFETCH IN MPD
Jul 24 17:05:50 volumio-small volumio[740]: info: ControllerMpd::sendMpdCommand add "NAS/Transmission/Baba Blues - 2019 - Glimmer Of Gold (FLAC)/03 - Walk On.flac"
Jul 24 17:05:50 volumio-small volumio[740]: info: sending command...
Jul 24 17:05:50 volumio-small volumio[740]: info:
Jul 24 17:05:50 volumio-small volumio[740]: ---------------------------- MPD announces system playlist update
Jul 24 17:05:50 volumio-small volumio[740]: info: Ignoring MPD Status Update
Jul 24 17:05:50 volumio-small volumio[740]: info: parsing response...
Jul 24 17:05:50 volumio-small volumio[740]: info: ControllerMpd::sendMpdCommand consume 1
Jul 24 17:05:50 volumio-small volumio[740]: info: sending command...
Jul 24 17:05:50 volumio-small volumio[740]: info: ------------------------------ 7ms
Jul 24 17:05:50 volumio-small volumio[740]: info: parsing response...
Jul 24 17:05:54 volumio-small volumio[740]: info: CoreStateMachine::startPlaybackTimer
Jul 24 17:05:54 volumio-small volumio[740]: info: CorePlayQueue::getTrack 2
Jul 24 17:05:54 volumio-small volumio[740]: info:
Jul 24 17:05:54 volumio-small volumio[740]: ---------------------------- MPD announces system playlist update
Jul 24 17:05:54 volumio-small volumio[740]: info: Ignoring MPD Status Update
Jul 24 17:05:54 volumio-small volumio[740]: info:
Jul 24 17:05:54 volumio-small volumio[740]: ---------------------------- MPD announces state update: player
Jul 24 17:05:54 volumio-small volumio[740]: info: ControllerMpd::getState
Jul 24 17:05:54 volumio-small volumio[740]: info: ControllerMpd::sendMpdCommand status
Jul 24 17:05:54 volumio-small volumio[740]: info: sending command...
Jul 24 17:05:54 volumio-small volumio[740]: info: ------------------------------ 8ms
Jul 24 17:05:54 volumio-small volumio[740]: info: parsing response...
Jul 24 17:05:54 volumio-small volumio[740]: info: ControllerMpd::parseState
Jul 24 17:05:54 volumio-small volumio[740]: info: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 17:05:54 volumio-small volumio[740]: info: sending command...
Jul 24 17:05:54 volumio-small volumio[740]: info: parsing response...
Jul 24 17:05:54 volumio-small volumio[740]: info: ControllerMpd::parseTrackInfo
Jul 24 17:05:54 volumio-small volumio[740]: info: ControllerMpd::pushState
Jul 24 17:05:54 volumio-small volumio[740]: info: CoreCommandRouter::servicePushState
Jul 24 17:05:54 volumio-small volumio[740]: info: CoreStateMachine::syncState
Jul 24 17:05:54 volumio-small volumio[740]: info: CorePlayQueue::getTrack 2
Jul 24 17:05:54 volumio-small volumio[740]: info: STATE SERVICE {"status":"play","position":0,"seek":46,"duration":297,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Walk On","artist":"Baba Blues","album":"Glimmer Of Gold","uri":"NAS/Transmission/Baba Blues - 2019 - Glimmer Of Gold (FLAC)/03 - Walk On.flac","trackType":"flac"}
Jul 24 17:05:54 volumio-small volumio[740]: info: CURRENT POSITION 2
Jul 24 17:05:54 volumio-small volumio[740]: info: CoreStateMachine::syncState stateService play
Jul 24 17:05:54 volumio-small volumio[740]: info: CoreStateMachine::syncState currentStatus play
Jul 24 17:05:54 volumio-small volumio[740]: info: Received an update from plugin. extracting info from payload
Jul 24 17:05:54 volumio-small volumio[740]: info: CoreStateMachine::pushState
Jul 24 17:05:54 volumio-small volumio[740]: info: CoreStateMachine::getState
Jul 24 17:05:54 volumio-small volumio[740]: info: CorePlayQueue::getTrack 2
Jul 24 17:05:54 volumio-small volumio[740]: info: CoreCommandRouter::volumioPushState
Jul 24 17:05:54 volumio-small volumio[740]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 17:05:54 volumio-small volumio[740]: info: CoreStateMachine::pushState
Jul 24 17:05:54 volumio-small volumio[740]: info: CoreStateMachine::getState
Jul 24 17:05:54 volumio-small volumio[740]: info: CorePlayQueue::getTrack 2
Jul 24 17:05:54 volumio-small volumio[740]: info: CoreCommandRouter::volumioPushState
Jul 24 17:05:54 volumio-small volumio[740]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 17:05:54 volumio-small volumio[740]: info: ------------------------------ 85ms
Jul 24 17:06:03 volumio-small volumio[740]: info: [{"prettyName":"Spotify","name":"spop","category":"music_service","version":"3.0.3","icon":"fa-spotify","enabled":false,"active":false}]
Jul 24 17:06:06 volumio-small volumio[740]: info: Enabling plugin spop
Jul 24 17:06:06 volumio-small volumio[740]: info: Loading plugin "spop"...
Jul 24 17:06:08 volumio-small volumio[740]: info: PLUGIN START: spop
Jul 24 17:06:08 volumio-small volumio[740]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jul 24 17:06:08 volumio-small volumio[740]: [SpotifyConnect] Creating VLS config file
Jul 24 17:06:08 volumio-small volumio[740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 24 17:06:08 volumio-small volumio[740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 24 17:06:08 volumio-small volumio[740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 24 17:06:08 volumio-small volumio[740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 24 17:06:08 volumio-small volumio[740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 24 17:06:08 volumio-small sudo[5719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts
Jul 24 17:06:08 volumio-small volumio[740]: [SpotifyConnect] Starting metadata listener
Jul 24 17:06:08 volumio-small sudo[5719]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 24 17:06:08 volumio-small sudo[5719]: pam_unix(sudo:session): session closed for user root
Jul 24 17:06:08 volumio-small sudo[5726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service
Jul 24 17:06:08 volumio-small sudo[5726]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 24 17:06:08 volumio-small volumio[740]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 24 17:06:08 volumio-small systemd[1]: Starting Volspotconnect2 Daemon...
Jul 24 17:06:08 volumio-small systemd[1]: Started Volspotconnect2 Daemon.
Jul 24 17:06:08 volumio-small sudo[5726]: pam_unix(sudo:session): session closed for user root
Jul 24 17:06:08 volumio-small volumio[5733]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11
Jul 24 17:06:08 volumio-small volumio[5733]: Reading Config from "/tmp/volspotify.toml"
Jul 24 17:06:08 volumio-small volumio[5733]: [Vollibrespot] : Using Alsa backend with device: plughw:1
Jul 24 17:06:08 volumio-small volumio[740]: [SpotifyConnect] Vollibrespot Daemon service started!
Jul 24 17:06:08 volumio-small volumio[740]: SpotifyConnect: 215.211ms
Jul 24 17:06:08 volumio-small volumio[740]: info: Done.
Jul 24 17:06:08 volumio-small volumio[5733]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" }
Jul 24 17:06:08 volumio-small volumio[740]: info: Successfully fixed Spotify hosts
Jul 24 17:06:12 volumio-small volumio[740]: info: Checking Spotify Web API
Jul 24 17:06:35 volumio-small volumio[740]: info: CALLMETHOD: music_service spop saveVolspotconnectSettings [object Object]
Jul 24 17:06:35 volumio-small volumio[740]: info: CoreCommandRouter::executeOnPlugin: spop , saveVolspotconnectSettings
Jul 24 17:06:35 volumio-small volumio[740]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 24 17:06:35 volumio-small volumio[740]: TypeError: Cannot read property 'config' of undefined
Jul 24 17:06:35 volumio-small volumio[740]: at ControllerSpotify.saveVolspotconnectSettings (/data/plugins/music_service/spop/index.js:2612:19)
Jul 24 17:06:35 volumio-small volumio[740]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1013:29)
Jul 24 17:06:35 volumio-small volumio[740]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:298:38)
Jul 24 17:06:35 volumio-small volumio[740]: at emitTwo (events.js:126:13)
Jul 24 17:06:35 volumio-small volumio[740]: at Socket.emit (events.js:214:7)
Jul 24 17:06:35 volumio-small volumio[740]: at /volumio/node_modules/socket.io/lib/socket.js:503:12
Jul 24 17:06:35 volumio-small volumio[740]: at _combinedTickCallback (internal/process/next_tick.js:131:7)
Jul 24 17:06:35 volumio-small volumio[740]: at process._tickCallback (internal/process/next_tick.js:180:9)
Jul 24 17:06:35 volumio-small volumio[740]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 24 17:06:37 volumio-small sudo[5931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-24 17:05
Jul 24 17:06:37 volumio-small sudo[5931]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)"
NAME="Raspbian GNU/Linux"
VERSION_ID="8"
VERSION="8 (jessie)"
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="b38474a34dd6501a9ca2801645a6a861fdbc549d"
VOLUMIO_FE_VERSION="f027ce75738c64189018057558e04cba7a74a080"
VOLUMIO_BE_VERSION="a98512120222cc847d1586e8d2068e08cb058c79"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Jul 26 16:22:33 CEST 2018"
VOLUMIO_VERSION="2.444"
VOLUMIO_HARDWARE="pi"
VOLUMIO_HASH="7f1396787ccc441a3d64fa9fc7c6c303"