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