-- Logs begin at Thu 2019-02-14 15:41:59 IST, end at Fri 2024-09-06 22:45:41 IST. --
Sep 06 22:44:28 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Sep 06 22:44:28 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Sep 06 22:44:28 localhost volumio[981]: info: Discovery: Getting this device information
Sep 06 22:44:28 localhost volumio[981]: info: CoreCommandRouter::volumioGetState
Sep 06 22:44:28 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:28 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 06 22:44:28 localhost volumio[981]: verbose: New Socket.io Connection to 192.168.0.3:3000 from 192.168.0.167 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5
Sep 06 22:44:28 localhost volumio[981]: info: CoreCommandRouter::volumioGetState
Sep 06 22:44:28 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:28 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Sep 06 22:44:28 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Sep 06 22:44:28 localhost volumio[981]: info: Discovery: Getting this device information
Sep 06 22:44:28 localhost volumio[981]: info: CoreCommandRouter::volumioGetState
Sep 06 22:44:28 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:28 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 06 22:44:28 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Sep 06 22:44:28 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Sep 06 22:44:30 localhost sudo[6673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Sep 06 22:44:30 localhost sudo[6673]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 06 22:44:30 localhost sudo[6673]: pam_unix(sudo:session): session closed for user root
Sep 06 22:44:30 localhost sudo[6676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 06 22:44:30 localhost sudo[6676]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 06 22:44:30 localhost sudo[6676]: pam_unix(sudo:session): session closed for user root
Sep 06 22:44:30 localhost volumio[981]: verbose: New Socket.io Connection to 192.168.0.3 from 192.168.0.167 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6
Sep 06 22:44:30 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Sep 06 22:44:30 localhost volumio[981]: info: CoreCommandRouter::volumioGetVisibleSources
Sep 06 22:44:30 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 06 22:44:30 localhost volumio[981]: info: CoreCommandRouter::volumioGetState
Sep 06 22:44:30 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:30 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Sep 06 22:44:30 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Sep 06 22:44:30 localhost volumio[981]: info: Received Get System Info
Sep 06 22:44:30 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Sep 06 22:44:30 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Sep 06 22:44:30 localhost volumio[981]: info: Discovery: Getting this device information
Sep 06 22:44:30 localhost volumio[981]: info: CoreCommandRouter::volumioGetState
Sep 06 22:44:30 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:30 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 06 22:44:30 localhost volumio[981]: info: CoreCommandRouter::volumioGetState
Sep 06 22:44:30 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:30 localhost volumio[981]: info: Listing playlists
Sep 06 22:44:30 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Sep 06 22:44:30 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Sep 06 22:44:30 localhost sudo[6679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Sep 06 22:44:30 localhost sudo[6679]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 06 22:44:30 localhost sudo[6679]: pam_unix(sudo:session): session closed for user root
Sep 06 22:44:30 localhost sudo[6682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 06 22:44:30 localhost sudo[6682]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 06 22:44:30 localhost sudo[6682]: pam_unix(sudo:session): session closed for user root
Sep 06 22:44:30 localhost volumio[981]: verbose: New Socket.io Connection to 192.168.0.3 from 192.168.0.167 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6
Sep 06 22:44:31 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Sep 06 22:44:31 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Sep 06 22:44:31 localhost volumio[981]: info: CoreCommandRouter::volumioGetVisibleSources
Sep 06 22:44:31 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 06 22:44:31 localhost volumio[981]: info: CoreCommandRouter::volumioGetState
Sep 06 22:44:31 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:31 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Sep 06 22:44:31 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Sep 06 22:44:31 localhost volumio[981]: info: Received Get System Info
Sep 06 22:44:31 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Sep 06 22:44:31 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Sep 06 22:44:31 localhost volumio[981]: info: Discovery: Getting this device information
Sep 06 22:44:31 localhost volumio[981]: info: CoreCommandRouter::volumioGetState
Sep 06 22:44:31 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:31 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 06 22:44:31 localhost volumio[981]: info: CoreCommandRouter::volumioGetState
Sep 06 22:44:31 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:31 localhost volumio[981]: info: Listing playlists
Sep 06 22:44:31 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Sep 06 22:44:31 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Sep 06 22:44:31 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Sep 06 22:44:32 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Sep 06 22:44:32 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Sep 06 22:44:32 localhost volumio[981]: info: Received Get System Info
Sep 06 22:44:32 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Sep 06 22:44:32 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Sep 06 22:44:32 localhost volumio[981]: info: Discovery: Getting this device information
Sep 06 22:44:32 localhost volumio[981]: info: CoreCommandRouter::volumioGetState
Sep 06 22:44:32 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:32 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 06 22:44:32 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Sep 06 22:44:34 localhost volumio[981]: info: CoreCommandRouter::volumioPlay
Sep 06 22:44:34 localhost volumio[981]: info: CoreStateMachine::play index undefined
Sep 06 22:44:34 localhost volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined
Sep 06 22:44:34 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:34 localhost volumio[981]: info: CoreStateMachine::startPlaybackTimer
Sep 06 22:44:34 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:34 localhost volumio[981]: info: [1725642874336] ControllerSpotify::clearAddPlayTrack
Sep 06 22:44:34 localhost volumio[981]: info: Sending Spotify command with payload to local API: /player/play
Sep 06 22:44:34 localhost go-librespot[1190]: time="2024-09-06T22:44:34+05:30" level=debug msg="renewing login5 access token"
Sep 06 22:44:34 localhost go-librespot[1190]: time="2024-09-06T22:44:34+05:30" level=debug msg="authenticated as afhla7vs2w13o49wuzx1r3xcn"
Sep 06 22:44:34 localhost go-librespot[1190]: time="2024-09-06T22:44:34+05:30" level=debug msg="resolved context of track" uri="spotify:track:5o7aBxuwv2dsxWFb3gqdxj"
Sep 06 22:44:34 localhost go-librespot[1190]: time="2024-09-06T22:44:34+05:30" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:5o7aBxuwv2dsxWFb3gqdxj"
Sep 06 22:44:34 localhost go-librespot[1190]: time="2024-09-06T22:44:34+05:30" level=debug msg="loading track (paused: false, position: 1ms)" uri="spotify:track:5o7aBxuwv2dsxWFb3gqdxj"
Sep 06 22:44:35 localhost go-librespot[1190]: time="2024-09-06T22:44:35+05:30" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Sep 06 22:44:35 localhost go-librespot[1190]: time="2024-09-06T22:44:35+05:30" level=trace msg="emitting websocket event: will_play"
Sep 06 22:44:35 localhost volumio[981]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:5o7aBxuwv2dsxWFb3gqdxj","play_origin":"go-librespot"}}
Sep 06 22:44:35 localhost go-librespot[1190]: time="2024-09-06T22:44:35+05:30" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241"
Sep 06 22:44:35 localhost go-librespot[1190]: time="2024-09-06T22:44:35+05:30" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update
Sep 06 22:44:35 localhost go-librespot[1190]: time="2024-09-06T22:44:35+05:30" level=debug msg="selected format OGG_VORBIS_320 (3fa9921b4b3e5746a00b0cea2a6e2910b4b0798b)" uri="spotify:track:5o7aBxuwv2dsxWFb3gqdxj"
Sep 06 22:44:35 localhost go-librespot[1190]: time="2024-09-06T22:44:35+05:30" level=debug msg="requested aes key for file 3fa9921b4b3e5746a00b0cea2a6e2910b4b0798b, gid: 5o7aBxuwv2dsxWFb3gqdxj"
Sep 06 22:44:35 localhost go-librespot[1190]: time="2024-09-06T22:44:35+05:30" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update
Sep 06 22:44:35 localhost go-librespot[1190]: time="2024-09-06T22:44:35+05:30" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2853"
Sep 06 22:44:35 localhost go-librespot[1190]: time="2024-09-06T22:44:35+05:30" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1878"
Sep 06 22:44:35 localhost go-librespot[1190]: time="2024-09-06T22:44:35+05:30" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update
Sep 06 22:44:35 localhost go-librespot[1190]: time="2024-09-06T22:44:35+05:30" level=debug msg="fetched first chunk of 9, total size is 4366304 bytes" uri="spotify:track:5o7aBxuwv2dsxWFb3gqdxj"
Sep 06 22:44:35 localhost go-librespot[1190]: time="2024-09-06T22:44:35+05:30" level=trace msg="seek to 1ms (diff: 1ms, samples: 44, bytes: 0)" uri="spotify:track:5o7aBxuwv2dsxWFb3gqdxj"
Sep 06 22:44:35 localhost go-librespot[1190]: time="2024-09-06T22:44:35+05:30" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 5011 us, period size = 221 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 99 frames"
Sep 06 22:44:35 localhost go-librespot[1190]: time="2024-09-06T22:44:35+05:30" level=debug msg="created new output device"
Sep 06 22:44:35 localhost go-librespot[1190]: time="2024-09-06T22:44:35+05:30" level=info msg="loaded track \"Relaxing Music For Stress Relief\" (paused: false, position: 1ms, duration: 128432ms, prefetched: false)" uri="spotify:track:5o7aBxuwv2dsxWFb3gqdxj"
Sep 06 22:44:35 localhost go-librespot[1190]: time="2024-09-06T22:44:35+05:30" level=debug msg="fetched chunk 2/8, size: 524288" uri="spotify:track:5o7aBxuwv2dsxWFb3gqdxj"
Sep 06 22:44:36 localhost go-librespot[1190]: time="2024-09-06T22:44:36+05:30" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Sep 06 22:44:36 localhost go-librespot[1190]: time="2024-09-06T22:44:36+05:30" level=trace msg="scheduling prefetch in 98s"
Sep 06 22:44:36 localhost go-librespot[1190]: time="2024-09-06T22:44:36+05:30" level=trace msg="emitting websocket event: metadata"
Sep 06 22:44:36 localhost volumio[981]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:5o7aBxuwv2dsxWFb3gqdxj","name":"Relaxing Music For Stress Relief","artist_names":["Relaxing Music for Stress Relief","Relaxing Music","Calm Music"],"album_name":"Calm Music For Stress Relief: Soothing Music For Relaxation, Spa, Massage, Yoga, Meditation, Sleep, Anxiety, Focus, Concentration, Healing, Wellness and Mindfulness","album_cover_url":"https://i.scdn.co/image/ab67616d00001e024773dad7571eac8965789b85","position":1,"duration":128432,"release_date":"year:2020 month:3 day:23","track_number":1,"disc_number":1}}
Sep 06 22:44:36 localhost go-librespot[1190]: time="2024-09-06T22:44:36+05:30" level=debug msg="fetched chunk 1/8, size: 524288" uri="spotify:track:5o7aBxuwv2dsxWFb3gqdxj"
Sep 06 22:44:36 localhost go-librespot[1190]: time="2024-09-06T22:44:36+05:30" level=debug msg="fetched chunk 3/8, size: 524288" uri="spotify:track:5o7aBxuwv2dsxWFb3gqdxj"
Sep 06 22:44:36 localhost go-librespot[1190]: time="2024-09-06T22:44:36+05:30" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Sep 06 22:44:36 localhost go-librespot[1190]: time="2024-09-06T22:44:36+05:30" level=trace msg="emitting websocket event: playing"
Sep 06 22:44:36 localhost volumio[981]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:5o7aBxuwv2dsxWFb3gqdxj","play_origin":"go-librespot"}}
Sep 06 22:44:36 localhost volumio[981]: SPOTIFY: PUSH STATE SPOTIFY
Sep 06 22:44:36 localhost volumio[981]: SPOTIFY: {"status":"play","service":"spop","title":"Relaxing Music For Stress Relief","artist":"Relaxing Music for Stress Relief, Relaxing Music, Calm Music","album":"Calm Music For Stress Relief: Soothing Music For Relaxation, Spa, Massage, Yoga, Meditation, Sleep, Anxiety, Focus, Concentration, Healing, Wellness and Mindfulness","albumart":"https://i.scdn.co/image/ab67616d00001e024773dad7571eac8965789b85","uri":"spotify:track:5o7aBxuwv2dsxWFb3gqdxj","trackType":"spotify","seek":1,"duration":128,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null}
Sep 06 22:44:36 localhost volumio[981]: info: CoreCommandRouter::servicePushState
Sep 06 22:44:36 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:36 localhost volumio[981]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Relaxing Music For Stress Relief","artist":"Relaxing Music for Stress Relief, Relaxing Music, Calm Music","album":"Calm Music For Stress Relief: Soothing Music For Relaxation, Spa, Massage, Yoga, Meditation, Sleep, Anxiety, Focus, Concentration, Healing, Wellness and Mindfulness","albumart":"https://i.scdn.co/image/ab67616d00001e024773dad7571eac8965789b85","uri":"spotify:track:5o7aBxuwv2dsxWFb3gqdxj","trackType":"spotify","seek":1,"duration":128,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null}
Sep 06 22:44:36 localhost volumio[981]: verbose: CURRENT POSITION 0
Sep 06 22:44:36 localhost volumio[981]: info: CoreStateMachine::syncState stateService play
Sep 06 22:44:36 localhost volumio[981]: info: CoreStateMachine::syncState currentStatus stop
Sep 06 22:44:36 localhost volumio[981]: SPOTIFY: PUSH STATE SPOTIFY
Sep 06 22:44:36 localhost volumio[981]: SPOTIFY: {"status":"play","service":"spop","title":"Relaxing Music For Stress Relief","artist":"Relaxing Music for Stress Relief, Relaxing Music, Calm Music","album":"Calm Music For Stress Relief: Soothing Music For Relaxation, Spa, Massage, Yoga, Meditation, Sleep, Anxiety, Focus, Concentration, Healing, Wellness and Mindfulness","albumart":"https://i.scdn.co/image/ab67616d00001e024773dad7571eac8965789b85","uri":"spotify:track:5o7aBxuwv2dsxWFb3gqdxj","trackType":"spotify","seek":1,"duration":128,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null}
Sep 06 22:44:36 localhost volumio[981]: info: CoreCommandRouter::servicePushState
Sep 06 22:44:36 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:36 localhost volumio[981]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Relaxing Music For Stress Relief","artist":"Relaxing Music for Stress Relief, Relaxing Music, Calm Music","album":"Calm Music For Stress Relief: Soothing Music For Relaxation, Spa, Massage, Yoga, Meditation, Sleep, Anxiety, Focus, Concentration, Healing, Wellness and Mindfulness","albumart":"https://i.scdn.co/image/ab67616d00001e024773dad7571eac8965789b85","uri":"spotify:track:5o7aBxuwv2dsxWFb3gqdxj","trackType":"spotify","seek":1,"duration":128,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null}
Sep 06 22:44:36 localhost volumio[981]: verbose: CURRENT POSITION 0
Sep 06 22:44:36 localhost volumio[981]: info: CoreStateMachine::syncState stateService play
Sep 06 22:44:36 localhost volumio[981]: info: CoreStateMachine::syncState currentStatus play
Sep 06 22:44:36 localhost volumio[981]: info: Received an update from plugin. extracting info from payload
Sep 06 22:44:36 localhost volumio[981]: info: CoreStateMachine::pushState
Sep 06 22:44:36 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:36 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 06 22:44:36 localhost volumio[981]: info: CoreCommandRouter::volumioPushState
Sep 06 22:44:36 localhost volumio[981]: info: CoreStateMachine::pushState
Sep 06 22:44:36 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:36 localhost volumio[981]: info: CoreCommandRouter::volumioPushState
Sep 06 22:44:36 localhost volumio[981]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70
Sep 06 22:44:36 localhost volumio[981]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70
Sep 06 22:44:37 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Sep 06 22:44:37 localhost volumio[981]: info: Received Get System Info
Sep 06 22:44:37 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Sep 06 22:44:37 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Sep 06 22:44:37 localhost volumio[981]: info: Discovery: Getting this device information
Sep 06 22:44:37 localhost volumio[981]: info: CoreCommandRouter::volumioGetState
Sep 06 22:44:37 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:37 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 06 22:44:38 localhost volumio[981]: info: CoreCommandRouter::volumioPause
Sep 06 22:44:38 localhost volumio[981]: info: CoreStateMachine::pause
Sep 06 22:44:38 localhost volumio[981]: info: CoreStateMachine::stPlaybackTimer
Sep 06 22:44:38 localhost volumio[981]: info: CoreStateMachine::servicePause
Sep 06 22:44:38 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:38 localhost volumio[981]: info: CoreCommandRouter::servicePause
Sep 06 22:44:38 localhost volumio[981]: info: Spotify Received pause
Sep 06 22:44:38 localhost volumio[981]: SPOTIFY: SPOTIFY PAUSE
Sep 06 22:44:38 localhost volumio[981]: SPOTIFY: {"status":"play","position":0,"title":"Relaxing Music For Stress Relief","artist":"Relaxing Music for Stress Relief","album":"Calm Music For Stress Relief: Soothing Music For Relaxation, Spa, Massage, Yoga, Meditation, Sleep, Anxiety, Focus, Concentration, Healing, Wellness and Mindfulness","albumart":"https://i.scdn.co/image/ab67616d0000b2734773dad7571eac8965789b85","uri":"spotify:track:5o7aBxuwv2dsxWFb3gqdxj","trackType":"spotify","codec":"ogg","seek":1,"duration":128,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":70,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"}
Sep 06 22:44:38 localhost volumio[981]: info: Sending Spotify command to local API: /player/pause
Sep 06 22:44:38 localhost go-librespot[1190]: time="2024-09-06T22:44:38+05:30" level=debug msg="pause track at 3154ms"
Sep 06 22:44:39 localhost go-librespot[1190]: time="2024-09-06T22:44:39+05:30" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Sep 06 22:44:39 localhost go-librespot[1190]: time="2024-09-06T22:44:39+05:30" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Sep 06 22:44:39 localhost go-librespot[1190]: time="2024-09-06T22:44:39+05:30" level=trace msg="emitting websocket event: paused"
Sep 06 22:44:39 localhost volumio[981]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:5o7aBxuwv2dsxWFb3gqdxj","play_origin":"go-librespot"}}
Sep 06 22:44:39 localhost volumio[981]: SPOTIFY: PUSH STATE SPOTIFY
Sep 06 22:44:39 localhost volumio[981]: SPOTIFY: {"status":"pause","service":"spop","title":"Relaxing Music For Stress Relief","artist":"Relaxing Music for Stress Relief, Relaxing Music, Calm Music","album":"Calm Music For Stress Relief: Soothing Music For Relaxation, Spa, Massage, Yoga, Meditation, Sleep, Anxiety, Focus, Concentration, Healing, Wellness and Mindfulness","albumart":"https://i.scdn.co/image/ab67616d00001e024773dad7571eac8965789b85","uri":"spotify:track:5o7aBxuwv2dsxWFb3gqdxj","trackType":"spotify","seek":3001,"duration":128,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null}
Sep 06 22:44:39 localhost volumio[981]: info: CoreCommandRouter::servicePushState
Sep 06 22:44:39 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:39 localhost volumio[981]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Relaxing Music For Stress Relief","artist":"Relaxing Music for Stress Relief, Relaxing Music, Calm Music","album":"Calm Music For Stress Relief: Soothing Music For Relaxation, Spa, Massage, Yoga, Meditation, Sleep, Anxiety, Focus, Concentration, Healing, Wellness and Mindfulness","albumart":"https://i.scdn.co/image/ab67616d00001e024773dad7571eac8965789b85","uri":"spotify:track:5o7aBxuwv2dsxWFb3gqdxj","trackType":"spotify","seek":3001,"duration":128,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null}
Sep 06 22:44:39 localhost volumio[981]: verbose: CURRENT POSITION 0
Sep 06 22:44:39 localhost volumio[981]: info: CoreStateMachine::syncState stateService pause
Sep 06 22:44:39 localhost volumio[981]: info: CoreStateMachine::syncState currentStatus pause
Sep 06 22:44:39 localhost volumio[981]: info: CoreStateMachine::pushState
Sep 06 22:44:39 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:39 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 06 22:44:39 localhost volumio[981]: info: CoreCommandRouter::volumioPushState
Sep 06 22:44:39 localhost volumio[981]: info: CoreStateMachine::stPlaybackTimer
Sep 06 22:44:39 localhost volumio[981]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70
Sep 06 22:44:40 localhost volumio[981]: info: CoreCommandRouter::volumioPlay
Sep 06 22:44:40 localhost volumio[981]: info: CoreStateMachine::play index undefined
Sep 06 22:44:40 localhost volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined
Sep 06 22:44:40 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:40 localhost volumio[981]: info: CoreStateMachine::startPlaybackTimer
Sep 06 22:44:40 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:40 localhost volumio[981]: info: Spotify Resume
Sep 06 22:44:40 localhost volumio[981]: info: Sending Spotify command to local API: /player/resume
Sep 06 22:44:40 localhost go-librespot[1190]: time="2024-09-06T22:44:40+05:30" level=trace msg="seek to 3154ms (diff: 53ms, samples: 139091, bytes: 97854)" uri="spotify:track:5o7aBxuwv2dsxWFb3gqdxj"
Sep 06 22:44:40 localhost go-librespot[1190]: time="2024-09-06T22:44:40+05:30" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 5011 us, period size = 221 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 99 frames"
Sep 06 22:44:40 localhost go-librespot[1190]: time="2024-09-06T22:44:40+05:30" level=debug msg="resume track at 3075ms"
Sep 06 22:44:40 localhost go-librespot[1190]: time="2024-09-06T22:44:40+05:30" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Sep 06 22:44:40 localhost go-librespot[1190]: time="2024-09-06T22:44:40+05:30" level=trace msg="scheduling prefetch in 95s"
Sep 06 22:44:40 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Sep 06 22:44:41 localhost go-librespot[1190]: time="2024-09-06T22:44:41+05:30" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Sep 06 22:44:41 localhost go-librespot[1190]: time="2024-09-06T22:44:41+05:30" level=trace msg="emitting websocket event: playing"
Sep 06 22:44:41 localhost volumio[981]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:5o7aBxuwv2dsxWFb3gqdxj","play_origin":"go-librespot"}}
Sep 06 22:44:41 localhost volumio[981]: SPOTIFY: PUSH STATE SPOTIFY
Sep 06 22:44:41 localhost volumio[981]: SPOTIFY: {"status":"play","service":"spop","title":"Relaxing Music For Stress Relief","artist":"Relaxing Music for Stress Relief, Relaxing Music, Calm Music","album":"Calm Music For Stress Relief: Soothing Music For Relaxation, Spa, Massage, Yoga, Meditation, Sleep, Anxiety, Focus, Concentration, Healing, Wellness and Mindfulness","albumart":"https://i.scdn.co/image/ab67616d00001e024773dad7571eac8965789b85","uri":"spotify:track:5o7aBxuwv2dsxWFb3gqdxj","trackType":"spotify","seek":3001,"duration":128,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null}
Sep 06 22:44:41 localhost volumio[981]: info: CoreCommandRouter::servicePushState
Sep 06 22:44:41 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:41 localhost volumio[981]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Relaxing Music For Stress Relief","artist":"Relaxing Music for Stress Relief, Relaxing Music, Calm Music","album":"Calm Music For Stress Relief: Soothing Music For Relaxation, Spa, Massage, Yoga, Meditation, Sleep, Anxiety, Focus, Concentration, Healing, Wellness and Mindfulness","albumart":"https://i.scdn.co/image/ab67616d00001e024773dad7571eac8965789b85","uri":"spotify:track:5o7aBxuwv2dsxWFb3gqdxj","trackType":"spotify","seek":3001,"duration":128,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null}
Sep 06 22:44:41 localhost volumio[981]: verbose: CURRENT POSITION 0
Sep 06 22:44:41 localhost volumio[981]: info: CoreStateMachine::syncState stateService play
Sep 06 22:44:41 localhost volumio[981]: info: CoreStateMachine::syncState currentStatus pause
Sep 06 22:44:41 localhost volumio[981]: info: CoreStateMachine::pushState
Sep 06 22:44:41 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:41 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 06 22:44:41 localhost volumio[981]: info: CoreCommandRouter::volumioPushState
Sep 06 22:44:41 localhost volumio[981]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70
Sep 06 22:44:41 localhost volumio[981]: SPOTIFY: PUSH STATE SPOTIFY
Sep 06 22:44:41 localhost volumio[981]: SPOTIFY: {"status":"play","service":"spop","title":"Relaxing Music For Stress Relief","artist":"Relaxing Music for Stress Relief, Relaxing Music, Calm Music","album":"Calm Music For Stress Relief: Soothing Music For Relaxation, Spa, Massage, Yoga, Meditation, Sleep, Anxiety, Focus, Concentration, Healing, Wellness and Mindfulness","albumart":"https://i.scdn.co/image/ab67616d00001e024773dad7571eac8965789b85","uri":"spotify:track:5o7aBxuwv2dsxWFb3gqdxj","trackType":"spotify","seek":3001,"duration":128,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null}
Sep 06 22:44:41 localhost volumio[981]: info: CoreCommandRouter::servicePushState
Sep 06 22:44:41 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:41 localhost volumio[981]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Relaxing Music For Stress Relief","artist":"Relaxing Music for Stress Relief, Relaxing Music, Calm Music","album":"Calm Music For Stress Relief: Soothing Music For Relaxation, Spa, Massage, Yoga, Meditation, Sleep, Anxiety, Focus, Concentration, Healing, Wellness and Mindfulness","albumart":"https://i.scdn.co/image/ab67616d00001e024773dad7571eac8965789b85","uri":"spotify:track:5o7aBxuwv2dsxWFb3gqdxj","trackType":"spotify","seek":3001,"duration":128,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null}
Sep 06 22:44:41 localhost volumio[981]: verbose: CURRENT POSITION 0
Sep 06 22:44:41 localhost volumio[981]: info: CoreStateMachine::syncState stateService play
Sep 06 22:44:41 localhost volumio[981]: info: CoreStateMachine::syncState currentStatus play
Sep 06 22:44:41 localhost volumio[981]: info: Received an update from plugin. extracting info from payload
Sep 06 22:44:41 localhost volumio[981]: info: CoreStateMachine::pushState
Sep 06 22:44:41 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:41 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 06 22:44:41 localhost volumio[981]: info: CoreCommandRouter::volumioPushState
Sep 06 22:44:41 localhost volumio[981]: info: CoreStateMachine::pushState
Sep 06 22:44:41 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:41 localhost volumio[981]: info: CoreCommandRouter::volumioPushState
Sep 06 22:44:41 localhost volumio[981]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70
Sep 06 22:44:41 localhost volumio[981]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70
Sep 06 22:44:49 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Sep 06 22:44:49 localhost volumio[981]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Sep 06 22:44:49 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Sep 06 22:44:49 localhost volumio[981]: info: Received Get System Version
Sep 06 22:44:49 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 06 22:44:49 localhost volumio[981]: info: Received Get System Info
Sep 06 22:44:49 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Sep 06 22:44:49 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Sep 06 22:44:49 localhost volumio[981]: info: Discovery: Getting this device information
Sep 06 22:44:49 localhost volumio[981]: info: CoreCommandRouter::volumioGetState
Sep 06 22:44:49 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:49 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 06 22:44:52 localhost go-librespot[1190]: time="2024-09-06T22:44:52+05:30" level=debug msg="fetched chunk 4/8, size: 524288" uri="spotify:track:5o7aBxuwv2dsxWFb3gqdxj"
Sep 06 22:44:53 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Sep 06 22:44:53 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Sep 06 22:44:53 localhost volumio[981]: info: Discovery: Getting this device information
Sep 06 22:44:53 localhost volumio[981]: info: CoreCommandRouter::volumioGetState
Sep 06 22:44:53 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:44:53 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 06 22:45:08 localhost go-librespot[1190]: time="2024-09-06T22:45:08+05:30" level=debug msg="fetched chunk 5/8, size: 524288" uri="spotify:track:5o7aBxuwv2dsxWFb3gqdxj"
Sep 06 22:45:23 localhost go-librespot[1190]: time="2024-09-06T22:45:23+05:30" level=debug msg="fetched chunk 6/8, size: 524288" uri="spotify:track:5o7aBxuwv2dsxWFb3gqdxj"
Sep 06 22:45:24 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Sep 06 22:45:24 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Sep 06 22:45:24 localhost volumio[981]: info: Discovery: Getting this device information
Sep 06 22:45:24 localhost volumio[981]: info: CoreCommandRouter::volumioGetState
Sep 06 22:45:24 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:45:24 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 06 22:45:24 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Sep 06 22:45:24 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Sep 06 22:45:24 localhost volumio[981]: info: Discovery: Getting this device information
Sep 06 22:45:24 localhost volumio[981]: info: CoreCommandRouter::volumioGetState
Sep 06 22:45:24 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:45:24 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 06 22:45:24 localhost volumio[981]: verbose: New Socket.io Connection to 192.168.0.3:3000 from 192.168.0.167 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5
Sep 06 22:45:25 localhost volumio[981]: verbose: New Socket.io Connection to 192.168.0.3 from 192.168.0.167 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6
Sep 06 22:45:25 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Sep 06 22:45:25 localhost volumio[981]: info: CoreCommandRouter::volumioGetVisibleSources
Sep 06 22:45:25 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 06 22:45:25 localhost volumio[981]: info: CoreCommandRouter::volumioGetState
Sep 06 22:45:25 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:45:25 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Sep 06 22:45:25 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Sep 06 22:45:25 localhost volumio[981]: info: Received Get System Info
Sep 06 22:45:25 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Sep 06 22:45:25 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Sep 06 22:45:25 localhost volumio[981]: info: Discovery: Getting this device information
Sep 06 22:45:25 localhost volumio[981]: info: CoreCommandRouter::volumioGetState
Sep 06 22:45:25 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:45:25 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Sep 06 22:45:25 localhost volumio[981]: info: CoreCommandRouter::volumioGetState
Sep 06 22:45:25 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:45:25 localhost volumio[981]: info: Listing playlists
Sep 06 22:45:25 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Sep 06 22:45:27 localhost volumio[981]: info: CoreCommandRouter::volumioPause
Sep 06 22:45:27 localhost volumio[981]: info: CoreStateMachine::pause
Sep 06 22:45:27 localhost volumio[981]: info: CoreStateMachine::stPlaybackTimer
Sep 06 22:45:27 localhost volumio[981]: info: CoreStateMachine::servicePause
Sep 06 22:45:27 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:45:27 localhost volumio[981]: info: CoreCommandRouter::servicePause
Sep 06 22:45:27 localhost volumio[981]: info: Spotify Received pause
Sep 06 22:45:27 localhost volumio[981]: SPOTIFY: SPOTIFY PAUSE
Sep 06 22:45:27 localhost volumio[981]: SPOTIFY: {"status":"play","position":0,"title":"Relaxing Music For Stress Relief","artist":"Relaxing Music for Stress Relief","album":"Calm Music For Stress Relief: Soothing Music For Relaxation, Spa, Massage, Yoga, Meditation, Sleep, Anxiety, Focus, Concentration, Healing, Wellness and Mindfulness","albumart":"https://i.scdn.co/image/ab67616d0000b2734773dad7571eac8965789b85","uri":"spotify:track:5o7aBxuwv2dsxWFb3gqdxj","trackType":"spotify","codec":"ogg","seek":3001,"duration":128,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":70,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"}
Sep 06 22:45:27 localhost volumio[981]: info: Sending Spotify command to local API: /player/pause
Sep 06 22:45:27 localhost go-librespot[1190]: time="2024-09-06T22:45:27+05:30" level=debug msg="pause track at 50128ms"
Sep 06 22:45:27 localhost go-librespot[1190]: time="2024-09-06T22:45:27+05:30" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Sep 06 22:45:28 localhost go-librespot[1190]: time="2024-09-06T22:45:28+05:30" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Sep 06 22:45:28 localhost go-librespot[1190]: time="2024-09-06T22:45:28+05:30" level=trace msg="emitting websocket event: paused"
Sep 06 22:45:28 localhost volumio[981]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:5o7aBxuwv2dsxWFb3gqdxj","play_origin":"go-librespot"}}
Sep 06 22:45:28 localhost volumio[981]: SPOTIFY: PUSH STATE SPOTIFY
Sep 06 22:45:28 localhost volumio[981]: SPOTIFY: {"status":"pause","service":"spop","title":"Relaxing Music For Stress Relief","artist":"Relaxing Music for Stress Relief, Relaxing Music, Calm Music","album":"Calm Music For Stress Relief: Soothing Music For Relaxation, Spa, Massage, Yoga, Meditation, Sleep, Anxiety, Focus, Concentration, Healing, Wellness and Mindfulness","albumart":"https://i.scdn.co/image/ab67616d00001e024773dad7571eac8965789b85","uri":"spotify:track:5o7aBxuwv2dsxWFb3gqdxj","trackType":"spotify","seek":50001,"duration":128,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null}
Sep 06 22:45:28 localhost volumio[981]: info: CoreCommandRouter::servicePushState
Sep 06 22:45:28 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:45:28 localhost volumio[981]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Relaxing Music For Stress Relief","artist":"Relaxing Music for Stress Relief, Relaxing Music, Calm Music","album":"Calm Music For Stress Relief: Soothing Music For Relaxation, Spa, Massage, Yoga, Meditation, Sleep, Anxiety, Focus, Concentration, Healing, Wellness and Mindfulness","albumart":"https://i.scdn.co/image/ab67616d00001e024773dad7571eac8965789b85","uri":"spotify:track:5o7aBxuwv2dsxWFb3gqdxj","trackType":"spotify","seek":50001,"duration":128,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null}
Sep 06 22:45:28 localhost volumio[981]: verbose: CURRENT POSITION 0
Sep 06 22:45:28 localhost volumio[981]: info: CoreStateMachine::syncState stateService pause
Sep 06 22:45:28 localhost volumio[981]: info: CoreStateMachine::syncState currentStatus pause
Sep 06 22:45:28 localhost volumio[981]: info: CoreStateMachine::pushState
Sep 06 22:45:28 localhost volumio[981]: info: CorePlayQueue::getTrack 0
Sep 06 22:45:28 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 06 22:45:28 localhost volumio[981]: info: CoreCommandRouter::volumioPushState
Sep 06 22:45:28 localhost volumio[981]: info: CoreStateMachine::stPlaybackTimer
Sep 06 22:45:28 localhost volumio[981]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70
Sep 06 22:45:30 localhost volumio[981]: info: CoreCommandRouter::volumioGetQueue
Sep 06 22:45:30 localhost volumio[981]: info: CoreStateMachine::getQueue
Sep 06 22:45:30 localhost volumio[981]: info: CorePlayQueue::getQueue
Sep 06 22:45:35 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Sep 06 22:45:35 localhost volumio[981]: info: Preload queue cleared
Sep 06 22:45:37 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Sep 06 22:45:37 localhost volumio[981]: info: Preload queue cleared
Sep 06 22:45:39 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Sep 06 22:45:39 localhost volumio[981]: info: Preload queue cleared
Sep 06 22:45:41 localhost volumio[981]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Sep 06 22:45:41 localhost volumio[981]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Sep 06 22:45:41 localhost volumio[981]: TypeError: Cannot read property 'length' of undefined
Sep 06 22:45:41 localhost volumio[981]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25
Sep 06 22:45:41 localhost volumio[981]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13
Sep 06 22:45:41 localhost volumio[981]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18)
Sep 06 22:45:41 localhost volumio[981]: at Parser.emit (events.js:315:20)
Sep 06 22:45:41 localhost volumio[981]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26)
Sep 06 22:45:41 localhost volumio[981]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35)
Sep 06 22:45:41 localhost volumio[981]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5)
Sep 06 22:45:41 localhost volumio[981]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7)
Sep 06 22:45:41 localhost volumio[981]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13)
Sep 06 22:45:41 localhost volumio[981]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31)
Sep 06 22:45:41 localhost volumio[981]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59)
Sep 06 22:45:41 localhost volumio[981]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19)
Sep 06 22:45:41 localhost volumio[981]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15)
Sep 06 22:45:41 localhost volumio[981]: at IncomingMessage.emit (events.js:327:22)
Sep 06 22:45:41 localhost volumio[981]: at endReadableNT (internal/streams/readable.js:1327:12)
Sep 06 22:45:41 localhost volumio[981]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Sep 06 22:45:41 localhost volumio[981]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Sep 06 22:45:41 localhost sudo[6786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-06 22:44
Sep 06 22:45:41 localhost sudo[6786]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST"
VOLUMIO_VERSION="3.742"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"