-- 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"