-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Wed 2024-07-24 22:24:41 UTC. -- Jul 24 22:23:05 volumio volumio[11220]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 37127865049985658030682199661355021546, audio_type: Track } } Jul 24 22:23:05 volumio volumio[11220]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 37127865049985658030682199661355021546, audio_type: Track }, track_id: SpotifyId { id: 37127865049985658030682199661355021546, audio_type: Track } } Jul 24 22:23:06 volumio volumio[1030]: [SpotifyConnect] Dices Que Te Vas Jul 24 22:23:06 volumio volumio[1030]: info: [1721859786010] ControllerSpotify::pushState Jul 24 22:23:06 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jul 24 22:23:06 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 24 22:23:06 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 24 22:23:06 volumio volumio[11220]: [Vollibrespot] : Event: SinkInactive Jul 24 22:23:06 volumio volumio[11220]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 37127865049985658030682199661355021546, audio_type: Track } } Jul 24 22:23:06 volumio volumio[1030]: [SpotifyConnect] Sink released Jul 24 22:23:06 volumio volumio[1030]: info: [1721859786016] ControllerSpotify::pushState Jul 24 22:23:06 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jul 24 22:23:06 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 24 22:23:06 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 24 22:23:06 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 24 22:23:06 volumio volumio[1030]: info: CorePlayQueue::getTrack 58 Jul 24 22:23:06 volumio volumio[1030]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"Dices Que Te Vas","artist":"KAROL G, Anuel AA","album":"OCEAN","albumart":"https://i.scdn.co/image/ab67616d0000b273813d0c955d8224bd5f919a75","uri":"spotify:track:0QHOIQxRlIPYPJ1HLgIN8S","trackType":"spotify","seek":0,"duration":203,"samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","channels":2,"stream":false,"repeatMode":"all"} Jul 24 22:23:06 volumio volumio[1030]: verbose: CURRENT POSITION 58 Jul 24 22:23:06 volumio volumio[1030]: info: CoreStateMachine::syncState stateService stop Jul 24 22:23:06 volumio volumio[1030]: info: CoreStateMachine::syncState currentStatus play Jul 24 22:23:06 volumio volumio[1030]: info: CoreStateMachine::play index undefined Jul 24 22:23:06 volumio volumio[1030]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 24 22:23:06 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 24 22:23:06 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 24 22:23:06 volumio volumio[1030]: info: CorePlayQueue::getTrack 59 Jul 24 22:23:06 volumio volumio[1030]: info: CoreStateMachine::startPlaybackTimer Jul 24 22:23:06 volumio volumio[1030]: info: CorePlayQueue::getTrack 59 Jul 24 22:23:06 volumio volumio[1030]: info: [1721859786023] ControllerSpotify::clearAddPlayTrack Jul 24 22:23:06 volumio volumio[1030]: info: Setting this device active Jul 24 22:23:06 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 24 22:23:06 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 24 22:23:06 volumio volumio[1030]: [SpotifyConnect] Device palyback is inactive Jul 24 22:23:06 volumio volumio[11220]: [Vollibrespot] : Fetching autoplay context uri Jul 24 22:23:06 volumio volumio[11220]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 37127865049985658030682199661355021546, audio_type: Track }, track_id: SpotifyId { id: 37127865049985658030682199661355021546, audio_type: Track } } Jul 24 22:23:06 volumio volumio[11220]: [Vollibrespot] : error 400 for uri hm://autoplay-enabled/query?uri= Jul 24 22:23:06 volumio volumio[11220]: [Vollibrespot] : AutoplayError: MercuryError Jul 24 22:23:06 volumio volumio[11220]: [Vollibrespot] : Fetching autoplay context uri Jul 24 22:23:06 volumio volumio[11220]: [Vollibrespot] : error 400 for uri hm://autoplay-enabled/query?uri= Jul 24 22:23:06 volumio volumio[11220]: [Vollibrespot] : AutoplayError: MercuryError Jul 24 22:23:06 volumio volumio[11220]: [Vollibrespot] : Loading with Spotify URI Jul 24 22:23:06 volumio volumio[11220]: [Vollibrespot] : (180960 ms) loaded Jul 24 22:23:07 volumio volumio[11220]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 37127865049985658030682199661355021546, audio_type: Track } } Jul 24 22:23:07 volumio volumio[11220]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 37127865049985658030682199661355021546, audio_type: Track }, track_id: SpotifyId { id: 90783497611235684044910576267253001786, audio_type: Track } } Jul 24 22:23:07 volumio volumio[1030]: [SpotifyConnect] Dices Que Te Vas Jul 24 22:23:07 volumio volumio[1030]: info: [1721859787092] ControllerSpotify::pushState Jul 24 22:23:07 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jul 24 22:23:07 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 24 22:23:07 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 24 22:23:07 volumio volumio[1030]: info: CorePlayQueue::getTrack 59 Jul 24 22:23:07 volumio volumio[1030]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"Dices Que Te Vas","artist":"KAROL G, Anuel AA","album":"OCEAN","albumart":"https://i.scdn.co/image/ab67616d0000b273813d0c955d8224bd5f919a75","uri":"spotify:track:0QHOIQxRlIPYPJ1HLgIN8S","trackType":"spotify","seek":0,"duration":203,"samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","channels":2,"stream":false,"repeatMode":"all"} Jul 24 22:23:07 volumio volumio[1030]: verbose: CURRENT POSITION 59 Jul 24 22:23:07 volumio volumio[1030]: info: CoreStateMachine::syncState stateService stop Jul 24 22:23:07 volumio volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Jul 24 22:23:07 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 24 22:23:07 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 24 22:23:07 volumio volumio[1030]: info: No code Jul 24 22:23:07 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 24 22:23:07 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 24 22:23:07 volumio volumio[1030]: [SpotifyConnect] Device palyback is inactive Jul 24 22:23:07 volumio volumio[11220]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 90783497611235684044910576267253001786, audio_type: Track } } Jul 24 22:23:07 volumio volumio[11220]: [Vollibrespot] : Event: SinkActive Jul 24 22:23:07 volumio volumio[1030]: [SpotifyConnect] Pineapple Jul 24 22:23:07 volumio volumio[1030]: [SpotifyConnect] Sink acquired Jul 24 22:23:07 volumio volumio[1030]: info: Continuing Spotify Session Jul 24 22:23:07 volumio volumio[1030]: info: Checking Spotify Web API Jul 24 22:23:08 volumio volumio[1030]: info: Is Not Connect Playback Jul 24 22:23:08 volumio volumio[1030]: info: [1721859788304] ControllerSpotify::pushState Jul 24 22:23:08 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jul 24 22:23:08 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 24 22:23:08 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 24 22:23:08 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 24 22:24:22 volumio hostapd[1023]: wlan0: STA 26:7b:e3:e7:b0:a7 IEEE 802.11: associated Jul 24 22:24:22 volumio hostapd[1023]: wlan0: STA 26:7b:e3:e7:b0:a7 RADIUS: starting accounting session 49AFD24D6021C269 Jul 24 22:24:22 volumio dnsmasq-dhcp[1025]: DHCPDISCOVER(wlan0) 26:7b:e3:e7:b0:a7 Jul 24 22:24:22 volumio dnsmasq-dhcp[1025]: DHCPOFFER(wlan0) 192.168.211.141 26:7b:e3:e7:b0:a7 Jul 24 22:24:23 volumio dnsmasq-dhcp[1025]: DHCPREQUEST(wlan0) 192.168.211.141 26:7b:e3:e7:b0:a7 Jul 24 22:24:23 volumio dnsmasq-dhcp[1025]: DHCPACK(wlan0) 192.168.211.141 26:7b:e3:e7:b0:a7 Jul 24 22:24:27 volumio sudo[15435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 24 22:24:27 volumio sudo[15435]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 22:24:27 volumio sudo[15435]: pam_unix(sudo:session): session closed for user root Jul 24 22:24:27 volumio sudo[15437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 24 22:24:27 volumio sudo[15437]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 22:24:27 volumio sudo[15437]: pam_unix(sudo:session): session closed for user root Jul 24 22:24:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.181 from 192.168.211.141 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 5 Jul 24 22:24:27 volumio sudo[15441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 24 22:24:27 volumio sudo[15441]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 22:24:27 volumio sudo[15441]: pam_unix(sudo:session): session closed for user root Jul 24 22:24:27 volumio sudo[15443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 24 22:24:27 volumio sudo[15443]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 24 22:24:27 volumio sudo[15443]: pam_unix(sudo:session): session closed for user root Jul 24 22:24:27 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.1.181 from 192.168.211.141 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 6 Jul 24 22:24:28 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 24 22:24:28 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 24 22:24:28 volumio volumio[1030]: info: Listing playlists Jul 24 22:24:28 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 24 22:24:28 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 24 22:24:28 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 24 22:24:28 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 24 22:24:34 volumio volumio[1030]: info: CoreCommandRouter::volumioGetQueue Jul 24 22:24:34 volumio volumio[1030]: info: CoreStateMachine::getQueue Jul 24 22:24:34 volumio volumio[1030]: info: CorePlayQueue::getQueue Jul 24 22:24:35 volumio volumio[1030]: info: CoreCommandRouter::volumioGetVisibleSources Jul 24 22:24:35 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 24 22:24:39 volumio volumio[1030]: Searching all installed plugins Jul 24 22:24:39 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 24 22:24:39 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: , search Jul 24 22:24:39 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 24 22:24:39 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 24 22:24:39 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 24 22:24:39 volumio volumio[1030]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 24 22:24:39 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Jul 24 22:24:39 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: spop , search Jul 24 22:24:40 volumio volumio[1030]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 24 22:24:40 volumio volumio[1030]: Error: read ECONNRESET Jul 24 22:24:40 volumio volumio[1030]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Jul 24 22:24:40 volumio volumio[1030]: errno: -104, Jul 24 22:24:40 volumio volumio[1030]: code: 'ECONNRESET', Jul 24 22:24:40 volumio volumio[1030]: syscall: 'read' Jul 24 22:24:40 volumio volumio[1030]: } Jul 24 22:24:40 volumio volumio[1030]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 24 22:24:41 volumio sudo[15459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-24 22:23 Jul 24 22:24:41 volumio sudo[15459]: 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="1f061e035f7cb2799b32d3520aa8e55cd07df6f9" VOLUMIO_FE_VERSION="9971a09df9c3edfdedf2ecf438999be8aba9db68" VOLUMIO_FE3_VERSION="3974def988c7b2392ddc85f9b8cb4692d5e8fda9" VOLUMIO_BE_VERSION="b3c53a818b9b3d0e5c13c9d346c4d613c4ca426f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 11 Jul 2022 04:03:33 PM CEST" VOLUMIO_VERSION="3.324" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5b2f59e6e90e57f326ca25c7ca839d52"