-- Logs begin at Sat 2024-01-13 23:31:16 UTC, end at Thu 2024-07-25 12:07:13 UTC. -- Jul 25 12:06:20 volumio volumio[1220]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 146015618032596748352246855898549903492, audio_type: Track } } Jul 25 12:06:20 volumio volumio[1220]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 146015618032596748352246855898549903492, audio_type: Track }, track_id: SpotifyId { id: 146015618032596748352246855898549903492, audio_type: Track } } Jul 25 12:06:20 volumio volumio[1034]: [SpotifyConnect] New Feeling Jul 25 12:06:20 volumio volumio[1034]: info: [1721909180526] ControllerSpotify::pushState Jul 25 12:06:20 volumio volumio[1034]: info: CoreCommandRouter::servicePushState Jul 25 12:06:20 volumio volumio[1034]: info: CoreStateMachine::pushState Jul 25 12:06:20 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Jul 25 12:06:20 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 12:06:20 volumio volumio[1220]: [Vollibrespot] : Event: SinkInactive Jul 25 12:06:20 volumio volumio[1220]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 146015618032596748352246855898549903492, audio_type: Track } } Jul 25 12:06:20 volumio volumio[1034]: [SpotifyConnect] Sink released Jul 25 12:06:20 volumio volumio[1034]: info: [1721909180779] ControllerSpotify::pushState Jul 25 12:06:20 volumio volumio[1034]: info: CoreCommandRouter::servicePushState Jul 25 12:06:20 volumio volumio[1034]: info: CoreStateMachine::pushState Jul 25 12:06:20 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Jul 25 12:06:20 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 12:06:20 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Jul 25 12:06:20 volumio volumio[1034]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"New Feeling","artist":"Pamungkas","album":"New Feeling","albumart":"https://i.scdn.co/image/ab67616d0000b27311229ffe073821e420b91253","uri":"spotify:track:3lhBdBZRpf9uVQPswDYjK4","trackType":"spotify","seek":0,"duration":258,"samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","channels":2,"stream":false,"repeatMode":"all"} Jul 25 12:06:20 volumio volumio[1034]: verbose: CURRENT POSITION 0 Jul 25 12:06:20 volumio volumio[1034]: info: CoreStateMachine::syncState stateService stop Jul 25 12:06:20 volumio volumio[1034]: info: CoreStateMachine::syncState currentStatus play Jul 25 12:06:20 volumio volumio[1034]: info: CoreStateMachine::play index undefined Jul 25 12:06:20 volumio volumio[1034]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 12:06:20 volumio volumio[1034]: info: CoreStateMachine::pushState Jul 25 12:06:20 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Jul 25 12:06:20 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 12:06:20 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Jul 25 12:06:20 volumio volumio[1034]: info: CoreStateMachine::startPlaybackTimer Jul 25 12:06:20 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Jul 25 12:06:20 volumio volumio[1034]: info: [1721909180814] ControllerSpotify::clearAddPlayTrack Jul 25 12:06:20 volumio volumio[1034]: info: Setting this device active Jul 25 12:06:20 volumio volumio[1034]: info: CoreStateMachine::pushState Jul 25 12:06:20 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Jul 25 12:06:20 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 12:06:20 volumio volumio[1034]: [SpotifyConnect] Device palyback is inactive Jul 25 12:06:20 volumio volumio[1034]: info: touch_display: Setting screensaver timeout to 120 seconds. Jul 25 12:06:21 volumio volumio[1034]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jul 25 12:06:21 volumio volumio[1034]: info: touch_display: Setting screensaver timeout to 120 seconds. Jul 25 12:06:21 volumio volumio[1220]: [Vollibrespot] : Fetching autoplay context uri Jul 25 12:06:21 volumio volumio[1220]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 146015618032596748352246855898549903492, audio_type: Track }, track_id: SpotifyId { id: 146015618032596748352246855898549903492, audio_type: Track } } Jul 25 12:06:21 volumio volumio[1220]: [Vollibrespot] : error 400 for uri hm://autoplay-enabled/query?uri= Jul 25 12:06:21 volumio volumio[1220]: [Vollibrespot] : AutoplayError: MercuryError Jul 25 12:06:21 volumio volumio[1220]: [Vollibrespot] : Fetching autoplay context uri Jul 25 12:06:21 volumio volumio[1220]: [Vollibrespot] : error 400 for uri hm://autoplay-enabled/query?uri= Jul 25 12:06:21 volumio volumio[1220]: [Vollibrespot] : AutoplayError: MercuryError Jul 25 12:06:21 volumio volumio[1034]: [SpotifyConnect] New Feeling Jul 25 12:06:21 volumio volumio[1034]: info: [1721909181787] ControllerSpotify::pushState Jul 25 12:06:21 volumio volumio[1034]: info: CoreCommandRouter::servicePushState Jul 25 12:06:21 volumio volumio[1220]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 146015618032596748352246855898549903492, audio_type: Track } } Jul 25 12:06:21 volumio volumio[1220]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 146015618032596748352246855898549903492, audio_type: Track }, track_id: SpotifyId { id: 146015618032596748352246855898549903492, audio_type: Track } } Jul 25 12:06:21 volumio volumio[1034]: info: CoreStateMachine::pushState Jul 25 12:06:21 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Jul 25 12:06:21 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 12:06:21 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Jul 25 12:06:21 volumio volumio[1034]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"New Feeling","artist":"Pamungkas","album":"New Feeling","albumart":"https://i.scdn.co/image/ab67616d0000b27311229ffe073821e420b91253","uri":"spotify:track:3lhBdBZRpf9uVQPswDYjK4","trackType":"spotify","seek":0,"duration":258,"samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","channels":2,"stream":false,"repeatMode":"all"} Jul 25 12:06:21 volumio volumio[1034]: verbose: CURRENT POSITION 0 Jul 25 12:06:21 volumio volumio[1034]: info: CoreStateMachine::syncState stateService stop Jul 25 12:06:21 volumio volumio[1034]: info: CoreStateMachine::syncState currentStatus stop Jul 25 12:06:21 volumio volumio[1034]: info: CoreStateMachine::pushState Jul 25 12:06:21 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Jul 25 12:06:21 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 12:06:21 volumio volumio[1034]: info: No code Jul 25 12:06:21 volumio volumio[1034]: info: CoreStateMachine::pushState Jul 25 12:06:21 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Jul 25 12:06:21 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 12:06:21 volumio volumio[1034]: [SpotifyConnect] Device palyback is inactive Jul 25 12:06:22 volumio volumio[1220]: [Vollibrespot] : Event: SinkActive Jul 25 12:06:22 volumio volumio[1034]: [SpotifyConnect] New Feeling Jul 25 12:06:22 volumio volumio[1034]: info: [1721909182169] ControllerSpotify::pushState Jul 25 12:06:22 volumio volumio[1034]: info: CoreCommandRouter::servicePushState Jul 25 12:06:22 volumio volumio[1034]: info: CoreStateMachine::pushState Jul 25 12:06:22 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Jul 25 12:06:22 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 12:06:22 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Jul 25 12:06:22 volumio volumio[1034]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"New Feeling","artist":"Pamungkas","album":"New Feeling","albumart":"https://i.scdn.co/image/ab67616d0000b27311229ffe073821e420b91253","uri":"spotify:track:3lhBdBZRpf9uVQPswDYjK4","trackType":"spotify","seek":0,"duration":258,"samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","channels":2,"stream":false,"repeatMode":"all"} Jul 25 12:06:22 volumio volumio[1034]: verbose: CURRENT POSITION 0 Jul 25 12:06:22 volumio volumio[1034]: info: CoreStateMachine::syncState stateService stop Jul 25 12:06:22 volumio volumio[1034]: info: CoreStateMachine::syncState currentStatus stop Jul 25 12:06:22 volumio volumio[1034]: info: CoreStateMachine::pushState Jul 25 12:06:22 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Jul 25 12:06:22 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 12:06:22 volumio volumio[1034]: info: No code Jul 25 12:06:22 volumio volumio[1034]: info: CoreStateMachine::pushState Jul 25 12:06:22 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Jul 25 12:06:22 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 12:06:22 volumio volumio[1034]: [SpotifyConnect] Sink acquired Jul 25 12:06:22 volumio volumio[1034]: info: Continuing Spotify Session Jul 25 12:06:22 volumio volumio[1034]: info: Checking Spotify Web API Jul 25 12:06:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 25 12:06:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 25 12:06:41 volumio volumio[1034]: info: Discovery: Getting this device information Jul 25 12:06:41 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Jul 25 12:06:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Jul 25 12:06:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getConf Jul 25 12:06:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getConf Jul 25 12:06:41 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Jul 25 12:06:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 25 12:06:41 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 25 12:06:50 volumio sudo[2855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 25 12:06:50 volumio sudo[2855]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:06:50 volumio sudo[2857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 25 12:06:50 volumio sudo[2857]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 12:06:50 volumio sudo[2855]: pam_unix(sudo:session): session closed for user root Jul 25 12:06:50 volumio sudo[2857]: pam_unix(sudo:session): session closed for user root Jul 25 12:06:50 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Jul 25 12:06:50 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 25 12:06:50 volumio volumio[1034]: info: Listing playlists Jul 25 12:06:50 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 25 12:06:50 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 25 12:06:50 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 25 12:06:50 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 25 12:06:51 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 25 12:06:51 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 25 12:06:52 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Jul 25 12:06:52 volumio volumio[1034]: info: CoreStateMachine::getQueue Jul 25 12:06:52 volumio volumio[1034]: info: CorePlayQueue::getQueue Jul 25 12:06:54 volumio volumio[1034]: info: CoreCommandRouter::volumioRemoveQueueItem Jul 25 12:06:54 volumio volumio[1034]: info: CoreStateMachine::removeQueueItem Jul 25 12:06:54 volumio volumio[1034]: info: CoreStateMachine::stop Jul 25 12:06:54 volumio volumio[1034]: info: CoreStateMachine::serviceStop Jul 25 12:06:54 volumio volumio[1034]: info: Received STOP, but no service to execute it Jul 25 12:06:54 volumio volumio[1034]: info: CorePlayQueue::removeQueueItem 0 Jul 25 12:06:54 volumio volumio[1034]: info: CorePlayQueue::saveQueue Jul 25 12:06:54 volumio volumio[1034]: info: CoreCommandRouter::volumioPushQueue Jul 25 12:06:54 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 25 12:06:55 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 25 12:06:55 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 25 12:07:06 volumio volumio[1034]: info: CoreCommandRouter::volumioGetVisibleSources Jul 25 12:07:06 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 25 12:07:09 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Jul 25 12:07:09 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 25 12:07:09 volumio volumio[1034]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 25 12:07:09 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 25 12:07:09 volumio volumio[1034]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Jul 25 12:07:09 volumio volumio[1034]: info: Received Get System Version Jul 25 12:07:09 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 25 12:07:10 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Jul 25 12:07:11 volumio volumio[1034]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 25 12:07:11 volumio volumio[1034]: info: CoreStateMachine::ClearQueue Jul 25 12:07:11 volumio volumio[1034]: info: CoreStateMachine::stop Jul 25 12:07:11 volumio volumio[1034]: info: CoreStateMachine::serviceStop Jul 25 12:07:11 volumio volumio[1034]: info: Received STOP, but no service to execute it Jul 25 12:07:11 volumio volumio[1034]: info: CorePlayQueue::clearPlayQueue Jul 25 12:07:11 volumio volumio[1034]: info: CorePlayQueue::saveQueue Jul 25 12:07:11 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Jul 25 12:07:11 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 12:07:11 volumio volumio[1034]: info: CoreCommandRouter::volumioPushQueue Jul 25 12:07:11 volumio volumio[1034]: info: CoreStateMachine::addQueueItems Jul 25 12:07:11 volumio volumio[1034]: info: CorePlayQueue::addQueueItems Jul 25 12:07:11 volumio volumio[1034]: info: Adding Item to queue: webkbs/0 Jul 25 12:07:11 volumio volumio[1034]: info: Exploding uri webkbs/0 in service personal_radio Jul 25 12:07:11 volumio volumio[1034]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 25 12:07:11 volumio volumio[1034]: ReferenceError: self is not defined Jul 25 12:07:11 volumio volumio[1034]: at ControllerPersonalRadio.errorToast (/data/plugins/music_service/personal_radio/index.js:575:22) Jul 25 12:07:11 volumio volumio[1034]: at /data/plugins/music_service/personal_radio/index.js:487:12 Jul 25 12:07:11 volumio volumio[1034]: at Request.handleRequestResponse [as _callback] (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:463:26) Jul 25 12:07:11 volumio volumio[1034]: at Request.self.callback (/data/plugins/music_service/personal_radio/node_modules/request/request.js:187:22) Jul 25 12:07:11 volumio volumio[1034]: at emitTwo (events.js:126:13) Jul 25 12:07:11 volumio volumio[1034]: at Request.emit (events.js:214:7) Jul 25 12:07:11 volumio volumio[1034]: at Request. (/data/plugins/music_service/personal_radio/node_modules/request/request.js:1044:10) Jul 25 12:07:11 volumio volumio[1034]: at emitOne (events.js:116:13) Jul 25 12:07:11 volumio volumio[1034]: at Request.emit (events.js:211:7) Jul 25 12:07:11 volumio volumio[1034]: at IncomingMessage. (/data/plugins/music_service/personal_radio/node_modules/request/request.js:965:12) Jul 25 12:07:11 volumio volumio[1034]: at emitNone (events.js:111:20) Jul 25 12:07:11 volumio volumio[1034]: at IncomingMessage.emit (events.js:208:7) Jul 25 12:07:11 volumio volumio[1034]: at endReadableNT (_stream_readable.js:1064:12) Jul 25 12:07:11 volumio volumio[1034]: at _combinedTickCallback (internal/process/next_tick.js:138:11) Jul 25 12:07:11 volumio volumio[1034]: at process._tickCallback (internal/process/next_tick.js:180:9) Jul 25 12:07:11 volumio volumio[1034]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 25 12:07:13 volumio sudo[2931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-25 12:06 Jul 25 12:07:13 volumio sudo[2931]: 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Sep 30 22:42:09 CEST 2021" VOLUMIO_VERSION="2.915" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="02ee61bb422c79b6e5c0b6d1847a78d9"