-- Logs begin at Thu 2019-02-14 02:11:59 PST, end at Fri 2024-11-29 15:01:18 PST. -- Nov 29 15:00:04 volumio go-librespot[1172]: time="2024-11-29T15:00:04-08:00" level=debug msg="fetched chunk 15/18, size: 524288" uri="spotify:track:2FRnf9qhLbvw8fu4IBXx78" Nov 29 15:00:18 volumio go-librespot[1172]: time="2024-11-29T15:00:18-08:00" level=debug msg="fetched chunk 16/18, size: 524288" uri="spotify:track:2FRnf9qhLbvw8fu4IBXx78" Nov 29 15:00:19 volumio ntpd[769]: 74.50.98.131 local addr 192.168.1.114 -> Nov 29 15:00:24 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 15:00:24 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 15:00:24 volumio volumio[982]: info: Discovery: Getting this device information Nov 29 15:00:24 volumio volumio[982]: info: CoreCommandRouter::volumioGetState Nov 29 15:00:24 volumio volumio[982]: info: CorePlayQueue::getTrack 3 Nov 29 15:00:24 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 15:00:25 volumio volumio[982]: verbose: New Socket.io Connection to 192.168.1.114:3000 from 192.168.1.70 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Nov 29 15:00:32 volumio go-librespot[1172]: time="2024-11-29T15:00:32-08:00" level=debug msg="fetched chunk 17/18, size: 524288" uri="spotify:track:2FRnf9qhLbvw8fu4IBXx78" Nov 29 15:00:44 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Nov 29 15:00:44 volumio volumio[982]: info: In handleBrowseUri, curUri=spotify Nov 29 15:00:44 volumio volumio[982]: info: Preload queue cleared Nov 29 15:00:44 volumio volumio[982]: info: Preload queue cleared Nov 29 15:00:44 volumio volumio[982]: info: Preload queue cleared Nov 29 15:00:44 volumio volumio[982]: info: Preload queue cleared Nov 29 15:00:46 volumio go-librespot[1172]: time="2024-11-29T15:00:46-08:00" level=debug msg="fetched chunk 18/18, size: 300700" uri="spotify:track:2FRnf9qhLbvw8fu4IBXx78" Nov 29 15:00:47 volumio volumio[982]: info: CoreCommandRouter::volumioPause Nov 29 15:00:47 volumio volumio[982]: info: CoreStateMachine::pause Nov 29 15:00:47 volumio volumio[982]: info: CoreStateMachine::stPlaybackTimer Nov 29 15:00:47 volumio volumio[982]: info: CoreStateMachine::servicePause Nov 29 15:00:47 volumio volumio[982]: info: CorePlayQueue::getTrack 3 Nov 29 15:00:47 volumio volumio[982]: info: CoreCommandRouter::servicePause Nov 29 15:00:47 volumio volumio[982]: info: Spotify Received pause Nov 29 15:00:47 volumio volumio[982]: SPOTIFY: SPOTIFY PAUSE Nov 29 15:00:47 volumio volumio[982]: SPOTIFY: {"status":"play","position":3,"title":"Last Christmas","artist":"Wham!","album":"LAST CHRISTMAS","albumart":"https://i.scdn.co/image/ab67616d0000b273f2d2adaa21ad616df6241e7d","uri":"spotify:track:2FRnf9qhLbvw8fu4IBXx78","trackType":"spotify","codec":"ogg","seek":251,"duration":262,"samplerate":"320 kbps","bitdepth":"16 bit","random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":86,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Nov 29 15:00:47 volumio volumio[982]: info: Sending Spotify command to local API: /player/pause Nov 29 15:00:47 volumio go-librespot[1172]: time="2024-11-29T15:00:47-08:00" level=debug msg="pause track at 212528ms" Nov 29 15:00:47 volumio go-librespot[1172]: time="2024-11-29T15:00:47-08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 29 15:00:47 volumio go-librespot[1172]: time="2024-11-29T15:00:47-08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 29 15:00:47 volumio go-librespot[1172]: time="2024-11-29T15:00:47-08:00" level=trace msg="emitting websocket event: paused" Nov 29 15:00:47 volumio volumio[982]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:2FRnf9qhLbvw8fu4IBXx78","play_origin":"go-librespot"}} Nov 29 15:00:47 volumio volumio[982]: SPOTIFY: PUSH STATE SPOTIFY Nov 29 15:00:47 volumio volumio[982]: SPOTIFY: {"status":"pause","service":"spop","title":"Last Christmas","artist":"Wham!","album":"LAST CHRISTMAS","albumart":"https://i.scdn.co/image/ab67616d00001e02f2d2adaa21ad616df6241e7d","uri":"spotify:track:2FRnf9qhLbvw8fu4IBXx78","trackType":"spotify","seek":212000,"duration":262,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 29 15:00:47 volumio volumio[982]: info: CoreCommandRouter::servicePushState Nov 29 15:00:47 volumio volumio[982]: info: CorePlayQueue::getTrack 3 Nov 29 15:00:47 volumio volumio[982]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Last Christmas","artist":"Wham!","album":"LAST CHRISTMAS","albumart":"https://i.scdn.co/image/ab67616d00001e02f2d2adaa21ad616df6241e7d","uri":"spotify:track:2FRnf9qhLbvw8fu4IBXx78","trackType":"spotify","seek":212000,"duration":262,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 29 15:00:47 volumio volumio[982]: verbose: CURRENT POSITION 3 Nov 29 15:00:47 volumio volumio[982]: info: CoreStateMachine::syncState stateService pause Nov 29 15:00:47 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus pause Nov 29 15:00:47 volumio volumio[982]: info: CoreStateMachine::pushState Nov 29 15:00:47 volumio volumio[982]: info: CorePlayQueue::getTrack 3 Nov 29 15:00:47 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 15:00:47 volumio volumio[982]: info: CoreCommandRouter::volumioPushState Nov 29 15:00:47 volumio volumio[982]: info: CoreStateMachine::stPlaybackTimer Nov 29 15:00:47 volumio volumio[982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Nov 29 15:00:56 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 15:00:56 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 15:00:56 volumio volumio[982]: info: Discovery: Getting this device information Nov 29 15:00:56 volumio volumio[982]: info: CoreCommandRouter::volumioGetState Nov 29 15:00:56 volumio volumio[982]: info: CorePlayQueue::getTrack 3 Nov 29 15:00:56 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 15:00:57 volumio volumio[982]: verbose: New Socket.io Connection to 192.168.1.114:3000 from 192.168.1.70 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Nov 29 15:00:58 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 29 15:00:58 volumio volumio[982]: info: Preload queue cleared Nov 29 15:01:02 volumio ntpd[769]: 23.150.40.242 local addr 192.168.1.114 -> Nov 29 15:01:03 volumio ntpd[769]: 74.208.25.46 local addr 192.168.1.114 -> Nov 29 15:01:06 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 29 15:01:06 volumio volumio[982]: info: CURURI: music-library Nov 29 15:01:06 volumio volumio[982]: error: Failed LSINFO: null Nov 29 15:01:06 volumio volumio[982]: info: Preload queue cleared Nov 29 15:01:12 volumio volumio[982]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 15:01:12 volumio volumio[982]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 15:01:12 volumio volumio[982]: info: Retrieving Cloud Streaming UI Nov 29 15:01:12 volumio volumio[982]: info: Getting Tidal Cloud Configuration Nov 29 15:01:12 volumio volumio[982]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 15:01:12 volumio volumio[982]: info: Getting Qobuz Cloud Configuration Nov 29 15:01:12 volumio volumio[982]: info: Asking plugin for UI Config Nov 29 15:01:12 volumio volumio[982]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 15:01:12 volumio volumio[982]: info: Getting Spotify Cloud Configuration Nov 29 15:01:12 volumio volumio[982]: info: Asking plugin for UI Config Nov 29 15:01:12 volumio volumio[982]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 15:01:12 volumio volumio[982]: info: Saving Spotify Acccount Nov 29 15:01:12 volumio volumio[982]: info: Got Tidal Cloud Configuration Nov 29 15:01:12 volumio volumio[982]: info: Got it Nov 29 15:01:12 volumio volumio[982]: info: Got it Nov 29 15:01:12 volumio volumio[982]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 29 15:01:12 volumio volumio[982]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 15:01:12 volumio volumio[982]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Nov 29 15:01:12 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 29 15:01:12 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 29 15:01:12 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 29 15:01:12 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 29 15:01:12 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 15:01:12 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 15:01:12 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 15:01:12 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 15:01:12 volumio volumio[982]: info: CoreCommandRouter::volumioGetBrowseSources Nov 29 15:01:12 volumio volumio[982]: info: CoreCommandRouter::volumioGetBrowseSources Nov 29 15:01:12 volumio volumio[982]: info: CoreCommandRouter::volumioGetBrowseSources Nov 29 15:01:12 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 29 15:01:12 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 29 15:01:16 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 29 15:01:18 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Nov 29 15:01:18 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Nov 29 15:01:18 volumio volumio[982]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 15:01:18 volumio volumio[982]: Error: getaddrinfo -3007 Nov 29 15:01:18 volumio volumio[982]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Nov 29 15:01:18 volumio volumio[982]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Nov 29 15:01:18 volumio volumio[982]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Nov 29 15:01:18 volumio volumio[982]: code: -3007, Nov 29 15:01:18 volumio volumio[982]: errno: -3007, Nov 29 15:01:18 volumio volumio[982]: syscall: 'getaddrinfo' Nov 29 15:01:18 volumio volumio[982]: } Nov 29 15:01:18 volumio volumio[982]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 15:01:18 volumio sudo[3389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-29 15:00 Nov 29 15:01:18 volumio sudo[3389]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"