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