-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2024-07-18 13:47:33 CEST. --
Jul 18 13:46:00 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:00 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:00 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:00 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:01 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:01 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:01 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:01 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:02 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:02 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:02 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:02 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:03 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:03 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:03 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:03 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:03 volumio go-librespot[1327]: time="2024-07-18T13:46:03+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 354"
Jul 18 13:46:03 volumio go-librespot[1327]: time="2024-07-18T13:46:03+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update
Jul 18 13:46:04 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:04 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:04 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:04 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:05 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:05 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:05 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:05 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:06 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:06 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:06 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:06 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:07 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:07 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:07 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:07 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:07 volumio go-librespot[1327]: time="2024-07-18T13:46:07+02:00" level=debug msg="handling transfer player command from 0b8da44473ddf35978e3d302120606780213b6e3"
Jul 18 13:46:07 volumio go-librespot[1327]: time="2024-07-18T13:46:07+02:00" level=trace msg="fetched new page 0 with 6 items (list: 6)"
Jul 18 13:46:07 volumio go-librespot[1327]: time="2024-07-18T13:46:07+02:00" level=debug msg="loading track spotify:track:6qdxrFxVkUd0bq5P6J132z (paused: false, position: 4093ms)"
Jul 18 13:46:07 volumio go-librespot[1327]: time="2024-07-18T13:46:07+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Jul 18 13:46:07 volumio go-librespot[1327]: time="2024-07-18T13:46:07+02:00" level=trace msg="emitting websocket event: will_play"
Jul 18 13:46:07 volumio volumio[989]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6qdxrFxVkUd0bq5P6J132z","play_origin":"search"}}
Jul 18 13:46:07 volumio go-librespot[1327]: time="2024-07-18T13:46:07+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241"
Jul 18 13:46:07 volumio go-librespot[1327]: time="2024-07-18T13:46:07+02:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:6qdxrFxVkUd0bq5P6J132z"
Jul 18 13:46:07 volumio go-librespot[1327]: time="2024-07-18T13:46:07+02:00" level=debug msg="requested aes key for file e1f8124ed343f21bb0a0a4a9aa5f81a108f8b5e1, gid: 6qdxrFxVkUd0bq5P6J132z"
Jul 18 13:46:07 volumio go-librespot[1327]: time="2024-07-18T13:46:07+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1593"
Jul 18 13:46:08 volumio go-librespot[1327]: time="2024-07-18T13:46:08+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1371"
Jul 18 13:46:08 volumio go-librespot[1327]: time="2024-07-18T13:46:08+02:00" level=debug msg="fetched first chunk of 19, total size is 9681120 bytes"
Jul 18 13:46:08 volumio go-librespot[1327]: time="2024-07-18T13:46:08+02:00" level=trace msg="seek to 4093ms (diff: 123ms, samples: 180501, bytes: 148035)"
Jul 18 13:46:08 volumio volumio[989]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Jul 18 13:46:08 volumio volumio[989]: info: FusionDsp - ---- read samplerate from file: 44100
Jul 18 13:46:08 volumio volumio[989]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 18 13:46:08 volumio go-librespot[1327]: time="2024-07-18T13:46:08+02:00" level=info msg="loaded track \"Gente Di Mare\" (uri: spotify:track:6qdxrFxVkUd0bq5P6J132z, paused: false, position: 4093ms, duration: 234146ms)"
Jul 18 13:46:08 volumio volumio[989]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Jul 18 13:46:08 volumio volumio[989]: info: FusionDsp - ---- read samplerate from file: 44100
Jul 18 13:46:08 volumio volumio[989]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 18 13:46:08 volumio volumio[989]: info: FusionDsp - Effects disabled
Jul 18 13:46:08 volumio volumio[989]: info: FusionDsp -
Jul 18 13:46:08 volumio volumio[989]: info: FusionDsp - Effects disabled
Jul 18 13:46:08 volumio volumio[989]: info: FusionDsp -
Jul 18 13:46:08 volumio volumio[989]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 18 13:46:08 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:08 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:08 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:08 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:08 volumio volumio[989]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 18 13:46:08 volumio go-librespot[1327]: time="2024-07-18T13:46:08+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Jul 18 13:46:08 volumio go-librespot[1327]: time="2024-07-18T13:46:08+02:00" level=trace msg="emitting websocket event: metadata"
Jul 18 13:46:08 volumio go-librespot[1327]: time="2024-07-18T13:46:08+02:00" level=trace msg="emitting websocket event: active"
Jul 18 13:46:08 volumio go-librespot[1327]: time="2024-07-18T13:46:08+02:00" level=debug msg="sending successful reply for delaer request"
Jul 18 13:46:08 volumio volumio[989]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6qdxrFxVkUd0bq5P6J132z","name":"Gente Di Mare","artist_names":["Umberto Tozzi","Raf"],"album_name":"Grandes Exitos","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02fe68c9732c166fc5293d8ae7","position":4093,"duration":234146,"release_date":"year:2001 month:3 day:2","track_number":12,"disc_number":1}}
Jul 18 13:46:08 volumio volumio[989]: SPOTIFY: received: {"type":"active","data":null}
Jul 18 13:46:08 volumio volumio[989]: info: Aligning Spotify Volume to Volumio Volume
Jul 18 13:46:08 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:08 volumio volumio[989]: info: Setting Spotify Volume from Volumio: 100
Jul 18 13:46:08 volumio go-librespot[1327]: time="2024-07-18T13:46:08+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update
Jul 18 13:46:08 volumio go-librespot[1327]: time="2024-07-18T13:46:08+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update
Jul 18 13:46:08 volumio go-librespot[1327]: time="2024-07-18T13:46:08+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update
Jul 18 13:46:08 volumio go-librespot[1327]: time="2024-07-18T13:46:08+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Jul 18 13:46:08 volumio go-librespot[1327]: time="2024-07-18T13:46:08+02:00" level=trace msg="emitting websocket event: playing"
Jul 18 13:46:08 volumio volumio[989]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:6qdxrFxVkUd0bq5P6J132z","play_origin":"search"}}
Jul 18 13:46:08 volumio volumio[989]: info: Spotify is playing in volatile mode
Jul 18 13:46:08 volumio volumio[989]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 18 13:46:08 volumio volumio[989]: SPOTIFY: UNSET VOLATILE
Jul 18 13:46:08 volumio volumio[989]: SPOTIFY: {"status":"play","position":0,"title":"OMD-Enola Gay-1980","artist":"70 80 Hits HQ","album":null,"albumart":"http://cdn-profiles.tunein.com/s267625/images/logoq.png?t=1689338248000","uri":"https://nr8.newradio.it:19574/stream","trackType":"webradio","seek":1962525,"duration":0,"samplerate":"","bitdepth":"","channels":2,"bitrate":"320 Kbps","random":false,"repeat":false,"repeatSingle":false,"consume":true,"volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":true,"updatedb":false,"volatile":false,"service":"webradio"}
Jul 18 13:46:08 volumio volumio[989]: info: Setting Spotify stop after unset volatile call
Jul 18 13:46:08 volumio volumio[989]: SPOTIFY: PUSH STATE SPOTIFY
Jul 18 13:46:08 volumio volumio[989]: SPOTIFY: {"status":"play","service":"spop","title":"Gente Di Mare","artist":"Umberto Tozzi, Raf","album":"Grandes Exitos","albumart":"https://i.scdn.co/image/ab67616d00001e02fe68c9732c166fc5293d8ae7","uri":"spotify:track:6qdxrFxVkUd0bq5P6J132z","trackType":"spotify","seek":4093,"duration":234,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null}
Jul 18 13:46:08 volumio volumio[989]: info: CoreCommandRouter::servicePushState
Jul 18 13:46:08 volumio volumio[989]: info: CoreStateMachine::pushState
Jul 18 13:46:08 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 18 13:46:08 volumio volumio[989]: info: CoreCommandRouter::volumioPushState
Jul 18 13:46:08 volumio volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 18 13:46:08 volumio go-librespot[1327]: time="2024-07-18T13:46:08+02:00" level=debug msg="fetched chunk 2/18, size: 524288"
Jul 18 13:46:08 volumio volumio[989]: SPOTIFY: PUSH STATE SPOTIFY
Jul 18 13:46:08 volumio volumio[989]: SPOTIFY: {"status":"play","service":"spop","title":"Gente Di Mare","artist":"Umberto Tozzi, Raf","album":"Grandes Exitos","albumart":"https://i.scdn.co/image/ab67616d00001e02fe68c9732c166fc5293d8ae7","uri":"spotify:track:6qdxrFxVkUd0bq5P6J132z","trackType":"spotify","seek":4093,"duration":234,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"}
Jul 18 13:46:08 volumio volumio[989]: info: CoreCommandRouter::servicePushState
Jul 18 13:46:08 volumio volumio[989]: info: CoreStateMachine::pushState
Jul 18 13:46:08 volumio volumio[989]: info: CoreCommandRouter::volumioPushState
Jul 18 13:46:08 volumio volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 18 13:46:09 volumio volumio[989]: info: Spotify Stop
Jul 18 13:46:09 volumio volumio[989]: SPOTIFY: SPOTIFY STOP
Jul 18 13:46:09 volumio volumio[989]: SPOTIFY: {"status":"play","title":"Gente Di Mare","artist":"Umberto Tozzi, Raf","album":"Grandes Exitos","albumart":"https://i.scdn.co/image/ab67616d00001e02fe68c9732c166fc5293d8ae7","uri":"spotify:track:6qdxrFxVkUd0bq5P6J132z","trackType":"spotify","codec":"ogg","seek":4093,"duration":234,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"}
Jul 18 13:46:09 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:09 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:09 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:09 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:09 volumio go-librespot[1327]: time="2024-07-18T13:46:09+02:00" level=debug msg="fetched chunk 1/18, size: 524288"
Jul 18 13:46:09 volumio go-librespot[1327]: time="2024-07-18T13:46:09+02:00" level=debug msg="fetched chunk 3/18, size: 524288"
Jul 18 13:46:09 volumio volumio[989]: SPOTIFY: SETTING SPOTIFY VOLUME 100
Jul 18 13:46:09 volumio volumio[989]: info: Sending Spotify command with payload to local API: /player/volume
Jul 18 13:46:09 volumio go-librespot[1327]: time="2024-07-18T13:46:09+02:00" level=debug msg="update volume to 65535/65535"
Jul 18 13:46:10 volumio go-librespot[1327]: time="2024-07-18T13:46:10+02:00" level=debug msg="put connect state because VOLUME_CHANGED"
Jul 18 13:46:10 volumio go-librespot[1327]: time="2024-07-18T13:46:10+02:00" level=trace msg="emitting websocket event: volume"
Jul 18 13:46:10 volumio volumio[989]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}}
Jul 18 13:46:10 volumio volumio[989]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100
Jul 18 13:46:10 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:10 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:10 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:10 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:11 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:11 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:11 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:11 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:12 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:12 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:12 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:12 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:13 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:13 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:13 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:13 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:14 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:14 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:14 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:14 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:15 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:15 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:15 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:15 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:16 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:16 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:16 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:16 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:17 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:17 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:17 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:17 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:18 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:18 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:18 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:18 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:19 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:19 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:19 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:19 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:20 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:20 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:20 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:20 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:21 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:21 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:21 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:21 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:22 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:22 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:22 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:22 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:22 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 18 13:46:22 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 18 13:46:22 volumio volumio[989]: info: Discovery: Getting this device information
Jul 18 13:46:22 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:22 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 18 13:46:22 volumio volumio[989]: verbose: New Socket.io Connection to 192.168.178.24 from 192.168.178.21 UA: unknown Engine version: 3 Transport: websocket Total Clients: 7
Jul 18 13:46:22 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:22 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Jul 18 13:46:22 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Jul 18 13:46:23 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:23 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:23 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:23 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:24 volumio sudo[7270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jul 18 13:46:24 volumio sudo[7270]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 18 13:46:24 volumio sudo[7270]: pam_unix(sudo:session): session closed for user root
Jul 18 13:46:24 volumio sudo[7273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 18 13:46:24 volumio sudo[7273]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 18 13:46:24 volumio sudo[7273]: pam_unix(sudo:session): session closed for user root
Jul 18 13:46:24 volumio volumio[989]: verbose: New Socket.io Connection to 192.168.178.24 from 192.168.178.21 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8
Jul 18 13:46:24 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jul 18 13:46:24 volumio volumio[989]: info: CoreCommandRouter::volumioGetVisibleSources
Jul 18 13:46:24 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 18 13:46:24 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:24 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Jul 18 13:46:24 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jul 18 13:46:24 volumio volumio[989]: info: Received Get System Info
Jul 18 13:46:24 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 18 13:46:24 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 18 13:46:24 volumio volumio[989]: info: Discovery: Getting this device information
Jul 18 13:46:24 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:24 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 18 13:46:24 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:24 volumio volumio[989]: info: Listing playlists
Jul 18 13:46:24 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jul 18 13:46:24 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jul 18 13:46:24 volumio volumio[989]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 18 13:46:24 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Jul 18 13:46:24 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:24 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:24 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:24 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:25 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:25 volumio go-librespot[1327]: time="2024-07-18T13:46:25+02:00" level=debug msg="fetched chunk 4/18, size: 524288"
Jul 18 13:46:25 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:25 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:25 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:25 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Jul 18 13:46:26 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 18 13:46:26 volumio volumio[989]: info: Received Get System Info
Jul 18 13:46:26 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 18 13:46:26 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 18 13:46:26 volumio volumio[989]: info: Discovery: Getting this device information
Jul 18 13:46:26 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:26 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 18 13:46:26 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:26 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:26 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:26 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:27 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:27 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:27 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:27 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:27 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 18 13:46:27 volumio volumio[989]: info: Received Get System Info
Jul 18 13:46:27 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 18 13:46:27 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 18 13:46:27 volumio volumio[989]: info: Discovery: Getting this device information
Jul 18 13:46:27 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:27 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 18 13:46:28 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:28 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:28 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:28 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:28 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:28 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:28 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:29 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:29 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:29 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:29 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:30 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:30 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:30 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:30 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:31 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:31 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:31 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:31 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:32 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:32 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:32 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:32 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:33 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:33 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:33 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:33 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:34 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Jul 18 13:46:34 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:34 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:34 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:34 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:35 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:35 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:35 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:35 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:36 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri
Jul 18 13:46:36 volumio volumio[989]: info: In handleBrowseUri, curUri=spotify
Jul 18 13:46:36 volumio volumio[989]: info: Preload queue cleared
Jul 18 13:46:36 volumio volumio[989]: info: Preload queue cleared
Jul 18 13:46:36 volumio volumio[989]: info: Preload queue cleared
Jul 18 13:46:36 volumio volumio[989]: info: Preload queue cleared
Jul 18 13:46:36 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:36 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:36 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:36 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:37 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:37 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:37 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:37 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:38 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:38 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:38 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:38 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:39 volumio volumio[989]: info: CoreCommandRouter::volumioPause
Jul 18 13:46:39 volumio volumio[989]: info: CoreStateMachine::pause
Jul 18 13:46:39 volumio volumio[989]: info: CoreStateMachine::stPlaybackTimer
Jul 18 13:46:39 volumio volumio[989]: info: CoreStateMachine::servicePause
Jul 18 13:46:39 volumio volumio[989]: info: CoreCommandRouter::servicePause
Jul 18 13:46:39 volumio volumio[989]: info: Spotify Received pause
Jul 18 13:46:39 volumio volumio[989]: SPOTIFY: SPOTIFY PAUSE
Jul 18 13:46:39 volumio volumio[989]: SPOTIFY: {"status":"play","title":"Gente Di Mare","artist":"Umberto Tozzi, Raf","album":"Grandes Exitos","albumart":"https://i.scdn.co/image/ab67616d00001e02fe68c9732c166fc5293d8ae7","uri":"spotify:track:6qdxrFxVkUd0bq5P6J132z","trackType":"spotify","codec":"ogg","seek":4093,"duration":234,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"}
Jul 18 13:46:39 volumio volumio[989]: info: Sending Spotify command to local API: /player/pause
Jul 18 13:46:39 volumio go-librespot[1327]: time="2024-07-18T13:46:39+02:00" level=debug msg="pause track at 14809ms"
Jul 18 13:46:39 volumio go-librespot[1327]: time="2024-07-18T13:46:39+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Jul 18 13:46:39 volumio go-librespot[1327]: time="2024-07-18T13:46:39+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Jul 18 13:46:39 volumio go-librespot[1327]: time="2024-07-18T13:46:39+02:00" level=trace msg="emitting websocket event: paused"
Jul 18 13:46:39 volumio volumio[989]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:6qdxrFxVkUd0bq5P6J132z","play_origin":"search"}}
Jul 18 13:46:39 volumio volumio[989]: SPOTIFY: PUSH STATE SPOTIFY
Jul 18 13:46:39 volumio volumio[989]: SPOTIFY: {"status":"pause","service":"spop","title":"Gente Di Mare","artist":"Umberto Tozzi, Raf","album":"Grandes Exitos","albumart":"https://i.scdn.co/image/ab67616d00001e02fe68c9732c166fc5293d8ae7","uri":"spotify:track:6qdxrFxVkUd0bq5P6J132z","trackType":"spotify","seek":34093,"duration":234,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"}
Jul 18 13:46:39 volumio volumio[989]: info: CoreCommandRouter::servicePushState
Jul 18 13:46:39 volumio volumio[989]: info: CoreStateMachine::pushState
Jul 18 13:46:39 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 18 13:46:39 volumio volumio[989]: info: CoreCommandRouter::volumioPushState
Jul 18 13:46:39 volumio volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 18 13:46:47 volumio sudo[7328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jul 18 13:46:47 volumio sudo[7328]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 18 13:46:47 volumio sudo[7328]: pam_unix(sudo:session): session closed for user root
Jul 18 13:46:47 volumio sudo[7331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 18 13:46:47 volumio sudo[7331]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 18 13:46:47 volumio sudo[7331]: pam_unix(sudo:session): session closed for user root
Jul 18 13:46:47 volumio volumio[989]: verbose: New Socket.io Connection to 192.168.178.24 from 192.168.178.22 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 7
Jul 18 13:46:48 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jul 18 13:46:48 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Jul 18 13:46:48 volumio volumio[989]: info: CoreCommandRouter::volumioGetVisibleSources
Jul 18 13:46:48 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 18 13:46:48 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:48 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Jul 18 13:46:48 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jul 18 13:46:48 volumio volumio[989]: info: Received Get System Info
Jul 18 13:46:48 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 18 13:46:48 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 18 13:46:48 volumio volumio[989]: info: Discovery: Getting this device information
Jul 18 13:46:48 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:48 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 18 13:46:48 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:48 volumio volumio[989]: info: Listing playlists
Jul 18 13:46:48 volumio sudo[7336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jul 18 13:46:48 volumio sudo[7336]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 18 13:46:48 volumio sudo[7336]: pam_unix(sudo:session): session closed for user root
Jul 18 13:46:48 volumio sudo[7339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 18 13:46:48 volumio sudo[7339]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 18 13:46:48 volumio sudo[7339]: pam_unix(sudo:session): session closed for user root
Jul 18 13:46:49 volumio volumio[989]: verbose: New Socket.io Connection to 192.168.178.24 from 192.168.178.22 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 7
Jul 18 13:46:49 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jul 18 13:46:49 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Jul 18 13:46:49 volumio volumio[989]: info: CoreCommandRouter::volumioGetVisibleSources
Jul 18 13:46:49 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 18 13:46:49 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:49 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Jul 18 13:46:49 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jul 18 13:46:49 volumio volumio[989]: info: Received Get System Info
Jul 18 13:46:49 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 18 13:46:49 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 18 13:46:49 volumio volumio[989]: info: Discovery: Getting this device information
Jul 18 13:46:49 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:49 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 18 13:46:49 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:49 volumio volumio[989]: info: Listing playlists
Jul 18 13:46:49 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jul 18 13:46:49 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jul 18 13:46:49 volumio volumio[989]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 18 13:46:50 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:50 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 18 13:46:50 volumio volumio[989]: info: Received Get System Info
Jul 18 13:46:50 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 18 13:46:50 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 18 13:46:50 volumio volumio[989]: info: Discovery: Getting this device information
Jul 18 13:46:50 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:50 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 18 13:46:51 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Jul 18 13:46:52 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 18 13:46:52 volumio volumio[989]: info: Received Get System Info
Jul 18 13:46:52 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 18 13:46:52 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 18 13:46:52 volumio volumio[989]: info: Discovery: Getting this device information
Jul 18 13:46:52 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:46:52 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 18 13:46:56 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:46:56 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:46:56 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:46:59 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Jul 18 13:47:05 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:07 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jul 18 13:47:07 volumio volumio[989]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Jul 18 13:47:07 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Jul 18 13:47:07 volumio volumio[989]: info: Received Get System Version
Jul 18 13:47:07 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 18 13:47:07 volumio volumio[989]: info: Received Get System Info
Jul 18 13:47:07 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 18 13:47:07 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 18 13:47:07 volumio volumio[989]: info: Discovery: Getting this device information
Jul 18 13:47:07 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:07 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 18 13:47:08 volumio volumio[989]: info: CoreCommandRouter::volumioVolatilePlay
Jul 18 13:47:08 volumio volumio[989]: info: CoreStateMachine::volatilePlay
Jul 18 13:47:08 volumio volumio[989]: info: Spotify Play
Jul 18 13:47:08 volumio volumio[989]: info: Sending Spotify command to local API: /player/resume
Jul 18 13:47:08 volumio go-librespot[1327]: time="2024-07-18T13:47:08+02:00" level=trace msg="seek to 14809ms (diff: 89ms, samples: 653076, bytes: 605732)"
Jul 18 13:47:08 volumio volumio[989]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Jul 18 13:47:08 volumio volumio[989]: info: FusionDsp - ---- read samplerate from file: 44100
Jul 18 13:47:08 volumio volumio[989]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 18 13:47:08 volumio go-librespot[1327]: time="2024-07-18T13:47:08+02:00" level=debug msg="resume track at 14349ms"
Jul 18 13:47:08 volumio volumio[989]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Jul 18 13:47:08 volumio volumio[989]: info: FusionDsp - ---- read samplerate from file: 44100
Jul 18 13:47:08 volumio volumio[989]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 18 13:47:08 volumio volumio[989]: info: FusionDsp - Effects disabled
Jul 18 13:47:08 volumio volumio[989]: info: FusionDsp -
Jul 18 13:47:08 volumio volumio[989]: info: FusionDsp - Effects disabled
Jul 18 13:47:08 volumio volumio[989]: info: FusionDsp -
Jul 18 13:47:08 volumio volumio[989]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 18 13:47:08 volumio volumio[989]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 18 13:47:08 volumio go-librespot[1327]: time="2024-07-18T13:47:08+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Jul 18 13:47:08 volumio go-librespot[1327]: time="2024-07-18T13:47:08+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Jul 18 13:47:08 volumio go-librespot[1327]: time="2024-07-18T13:47:08+02:00" level=trace msg="emitting websocket event: playing"
Jul 18 13:47:08 volumio volumio[989]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:6qdxrFxVkUd0bq5P6J132z","play_origin":"search"}}
Jul 18 13:47:08 volumio volumio[989]: SPOTIFY: PUSH STATE SPOTIFY
Jul 18 13:47:08 volumio volumio[989]: SPOTIFY: {"status":"play","service":"spop","title":"Gente Di Mare","artist":"Umberto Tozzi, Raf","album":"Grandes Exitos","albumart":"https://i.scdn.co/image/ab67616d00001e02fe68c9732c166fc5293d8ae7","uri":"spotify:track:6qdxrFxVkUd0bq5P6J132z","trackType":"spotify","seek":34093,"duration":234,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"}
Jul 18 13:47:08 volumio volumio[989]: info: CoreCommandRouter::servicePushState
Jul 18 13:47:08 volumio volumio[989]: info: CoreStateMachine::pushState
Jul 18 13:47:08 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 18 13:47:08 volumio volumio[989]: info: CoreCommandRouter::volumioPushState
Jul 18 13:47:08 volumio volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 18 13:47:09 volumio volumio[989]: SPOTIFY: PUSH STATE SPOTIFY
Jul 18 13:47:09 volumio volumio[989]: SPOTIFY: {"status":"play","service":"spop","title":"Gente Di Mare","artist":"Umberto Tozzi, Raf","album":"Grandes Exitos","albumart":"https://i.scdn.co/image/ab67616d00001e02fe68c9732c166fc5293d8ae7","uri":"spotify:track:6qdxrFxVkUd0bq5P6J132z","trackType":"spotify","seek":34093,"duration":234,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"}
Jul 18 13:47:09 volumio volumio[989]: info: CoreCommandRouter::servicePushState
Jul 18 13:47:09 volumio volumio[989]: info: CoreStateMachine::pushState
Jul 18 13:47:09 volumio volumio[989]: info: CoreCommandRouter::volumioPushState
Jul 18 13:47:09 volumio volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Jul 18 13:47:09 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:09 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:47:09 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:47:09 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:47:10 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:10 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:47:10 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:47:10 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:47:11 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:11 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:47:11 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:47:11 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:47:12 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:12 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:47:12 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:47:12 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:47:13 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:13 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:47:13 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:47:13 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:47:14 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:14 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:47:14 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:47:14 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:47:15 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:15 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:47:15 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:47:15 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:47:16 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:16 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:47:16 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:47:16 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:47:17 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:17 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:47:17 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:47:17 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:47:18 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:18 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:47:18 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:47:18 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:47:19 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:19 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:47:19 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:47:19 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:47:20 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:20 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:47:20 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:47:20 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:47:21 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:21 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:47:21 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:47:21 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:47:22 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:22 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:47:22 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:47:22 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:47:23 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:23 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:47:23 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:47:23 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:47:24 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:24 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:47:24 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:47:24 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:47:25 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:25 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:47:25 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:47:25 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:47:26 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:26 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:47:26 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:47:26 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:47:27 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:27 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:47:27 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:47:27 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:47:28 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:28 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:47:28 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:47:28 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:47:29 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:29 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:47:29 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:47:29 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:47:30 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:30 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:47:30 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:47:30 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:47:31 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:31 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:47:31 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:47:31 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:47:32 volumio volumio[989]: info: CoreCommandRouter::volumioGetState
Jul 18 13:47:32 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue
Jul 18 13:47:32 volumio volumio[989]: info: CoreStateMachine::getQueue
Jul 18 13:47:32 volumio volumio[989]: info: CorePlayQueue::getQueue
Jul 18 13:47:32 volumio volumio[989]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________
Jul 18 13:47:32 volumio volumio[989]: info: PLUGIN onReboot : networkfs
Jul 18 13:47:32 volumio volumio[989]: info: PLUGIN onReboot : audiophonicsonoff
Jul 18 13:47:32 volumio volumio[989]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 18 13:47:32 volumio volumio[989]: TypeError: Cannot read property 'writeSync' of undefined
Jul 18 13:47:32 volumio volumio[989]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25)
Jul 18 13:47:32 volumio volumio[989]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30)
Jul 18 13:47:32 volumio volumio[989]: at HashMap. (/volumio/app/pluginmanager.js:668:31)
Jul 18 13:47:32 volumio volumio[989]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10)
Jul 18 13:47:32 volumio volumio[989]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7)
Jul 18 13:47:32 volumio volumio[989]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20)
Jul 18 13:47:32 volumio volumio[989]: at CoreCommandRouter.reboot (/volumio/app/index.js:1331:22)
Jul 18 13:47:32 volumio volumio[989]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:857:33)
Jul 18 13:47:32 volumio volumio[989]: at Socket.emit (events.js:315:20)
Jul 18 13:47:32 volumio volumio[989]: at /volumio/node_modules/socket.io/lib/socket.js:528:12
Jul 18 13:47:32 volumio volumio[989]: at processTicksAndRejections (internal/process/task_queues.js:75:11)
Jul 18 13:47:32 volumio volumio[989]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 18 13:47:33 volumio sudo[7486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-18 13:46
Jul 18 13:47:33 volumio sudo[7486]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST"
VOLUMIO_VERSION="3.703"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"