-- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Fri 2024-08-30 21:02:26 UTC. -- Aug 30 21:01:34 volumio go-librespot[1526]: time="2024-08-30T21:01:34Z" level=debug msg="handling transfer player command from a59a16420e54b47c2756606a70bee2c2808a52a8" Aug 30 21:01:34 volumio go-librespot[1526]: time="2024-08-30T21:01:34Z" level=debug msg="resolved context of track" uri="spotify:user:thomasvd9-nl:collection" Aug 30 21:01:34 volumio go-librespot[1526]: time="2024-08-30T21:01:34Z" level=trace msg="fetched new page 0 with 1188 items (list: 1188)" uri="spotify:user:thomasvd9-nl:collection" Aug 30 21:01:34 volumio go-librespot[1526]: time="2024-08-30T21:01:34Z" level=debug msg="loading track (paused: true, position: 203332ms)" uri="spotify:track:6wrptu9SlLfq8bE6C86kEX" Aug 30 21:01:34 volumio go-librespot[1526]: time="2024-08-30T21:01:34Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 30 21:01:34 volumio go-librespot[1526]: time="2024-08-30T21:01:34Z" level=trace msg="emitting websocket event: will_play" Aug 30 21:01:34 volumio volumio[1192]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6wrptu9SlLfq8bE6C86kEX","play_origin":"collection-songs"}} Aug 30 21:01:34 volumio go-librespot[1526]: time="2024-08-30T21:01:34Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Aug 30 21:01:34 volumio go-librespot[1526]: time="2024-08-30T21:01:34Z" level=debug msg="selected format OGG_VORBIS_320 (5d9ee593a7b68835e6210a9a29b5845afafdac4d)" uri="spotify:track:6wrptu9SlLfq8bE6C86kEX" Aug 30 21:01:34 volumio go-librespot[1526]: time="2024-08-30T21:01:34Z" level=debug msg="requested aes key for file 5d9ee593a7b68835e6210a9a29b5845afafdac4d, gid: 6wrptu9SlLfq8bE6C86kEX" Aug 30 21:01:34 volumio go-librespot[1526]: time="2024-08-30T21:01:34Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1368" Aug 30 21:01:34 volumio go-librespot[1526]: time="2024-08-30T21:01:34Z" level=debug msg="fetched first chunk of 18, total size is 9212560 bytes" uri="spotify:track:6wrptu9SlLfq8bE6C86kEX" Aug 30 21:01:34 volumio go-librespot[1526]: time="2024-08-30T21:01:34Z" level=debug msg="fetched chunk 1/17, size: 524288" uri="spotify:track:6wrptu9SlLfq8bE6C86kEX" Aug 30 21:01:35 volumio go-librespot[1526]: time="2024-08-30T21:01:35Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1138" Aug 30 21:01:35 volumio go-librespot[1526]: time="2024-08-30T21:01:35Z" level=debug msg="fetched chunk 3/17, size: 524288" uri="spotify:track:6wrptu9SlLfq8bE6C86kEX" Aug 30 21:01:35 volumio go-librespot[1526]: time="2024-08-30T21:01:35Z" level=debug msg="fetched chunk 17/17, size: 299664" uri="spotify:track:6wrptu9SlLfq8bE6C86kEX" Aug 30 21:01:35 volumio go-librespot[1526]: time="2024-08-30T21:01:35Z" level=debug msg="fetched chunk 16/17, size: 524288" uri="spotify:track:6wrptu9SlLfq8bE6C86kEX" Aug 30 21:01:35 volumio go-librespot[1526]: time="2024-08-30T21:01:35Z" level=debug msg="fetched chunk 2/17, size: 524288" uri="spotify:track:6wrptu9SlLfq8bE6C86kEX" Aug 30 21:01:35 volumio go-librespot[1526]: time="2024-08-30T21:01:35Z" level=trace msg="seek to 203332ms (diff: 127ms, samples: 8966941, bytes: 8684918)" uri="spotify:track:6wrptu9SlLfq8bE6C86kEX" Aug 30 21:01:35 volumio volumio[1192]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Aug 30 21:01:35 volumio volumio[1192]: info: FusionDsp - ---- read samplerate from file: 44100 Aug 30 21:01:35 volumio volumio[1192]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 30 21:01:35 volumio go-librespot[1526]: time="2024-08-30T21:01:35Z" level=debug msg="created new output device" Aug 30 21:01:35 volumio go-librespot[1526]: time="2024-08-30T21:01:35Z" level=info msg="loaded track \"Che Vuole Questa Musica Stasera\" (paused: true, position: 203332ms, duration: 217186ms, prefetched: false)" uri="spotify:track:6wrptu9SlLfq8bE6C86kEX" Aug 30 21:01:35 volumio volumio[1192]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Aug 30 21:01:35 volumio volumio[1192]: info: FusionDsp - ---- read samplerate from file: 44100 Aug 30 21:01:35 volumio volumio[1192]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 30 21:01:35 volumio volumio[1192]: info: FusionDsp - {"Reload":{"result":"Ok"}} Aug 30 21:01:35 volumio volumio[1192]: info: FusionDsp - {"Reload":{"result":"Ok"}} Aug 30 21:01:35 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:35 volumio volumio[1192]: info: camilladsp spawned new process with pid 5253, instance 1, run: true Aug 30 21:01:35 volumio go-librespot[1526]: time="2024-08-30T21:01:35Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 30 21:01:35 volumio go-librespot[1526]: time="2024-08-30T21:01:35Z" level=trace msg="emitting websocket event: metadata" Aug 30 21:01:35 volumio go-librespot[1526]: time="2024-08-30T21:01:35Z" level=trace msg="emitting websocket event: active" Aug 30 21:01:35 volumio go-librespot[1526]: time="2024-08-30T21:01:35Z" level=debug msg="sending successful reply for dealer request" Aug 30 21:01:35 volumio go-librespot[1526]: time="2024-08-30T21:01:35Z" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Aug 30 21:01:35 volumio go-librespot[1526]: time="2024-08-30T21:01:35Z" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Aug 30 21:01:35 volumio go-librespot[1526]: time="2024-08-30T21:01:35Z" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Aug 30 21:01:35 volumio volumio[1192]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6wrptu9SlLfq8bE6C86kEX","name":"Che Vuole Questa Musica Stasera","artist_names":["Armando Trovajoli"],"album_name":"Profumo di donna (Original Motion Picture Soundtrack)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02e1a6205af714eaea5749dfc6","position":203332,"duration":217186,"release_date":"year:2003","track_number":9,"disc_number":1}} Aug 30 21:01:35 volumio volumio[1192]: SPOTIFY: received: {"type":"active","data":null} Aug 30 21:01:35 volumio volumio[1192]: info: Aligning Spotify Volume to Volumio Volume Aug 30 21:01:35 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:01:35 volumio volumio[1192]: info: CorePlayQueue::getTrack 0 Aug 30 21:01:35 volumio volumio[1192]: info: Setting Spotify Volume from Volumio: 100 Aug 30 21:01:35 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:35 volumio go-librespot[1526]: time="2024-08-30T21:01:35Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 30 21:01:35 volumio go-librespot[1526]: time="2024-08-30T21:01:35Z" level=trace msg="emitting websocket event: paused" Aug 30 21:01:35 volumio volumio[1192]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:6wrptu9SlLfq8bE6C86kEX","play_origin":"collection-songs"}} Aug 30 21:01:35 volumio volumio[1192]: info: Spotify is playing in volatile mode Aug 30 21:01:35 volumio volumio[1192]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 21:01:35 volumio volumio[1192]: SPOTIFY: UNSET VOLATILE Aug 30 21:01:35 volumio volumio[1192]: SPOTIFY: {"status":"stop","position":0,"title":"Via con me","artist":"Paolo Conte","album":"Paris Milonga","albumart":"https://i.scdn.co/image/ab67616d0000b2730331c7927206bd9f0c9d6fa9","uri":"spotify:track:5WHWPRAkR2WYH1CjKV9Omh","trackType":"spotify","codec":"ogg","seek":0,"duration":166,"samplerate":"320 kbps","bitdepth":"16 bit","random":true,"repeat":null,"repeatSingle":false,"consume":false,"volume":"100","dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","updatedb":false,"volatile":false,"service":"spop"} Aug 30 21:01:35 volumio volumio[1192]: SPOTIFY: PUSH STATE SPOTIFY Aug 30 21:01:35 volumio volumio[1192]: SPOTIFY: {"status":"pause","service":"spop","title":"Che Vuole Questa Musica Stasera","artist":"Armando Trovajoli","album":"Profumo di donna (Original Motion Picture Soundtrack)","albumart":"https://i.scdn.co/image/ab67616d00001e02e1a6205af714eaea5749dfc6","uri":"spotify:track:6wrptu9SlLfq8bE6C86kEX","trackType":"spotify","seek":203332,"duration":217,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 30 21:01:35 volumio volumio[1192]: info: CoreCommandRouter::servicePushState Aug 30 21:01:35 volumio volumio[1192]: info: CoreStateMachine::pushState Aug 30 21:01:35 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 21:01:35 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState Aug 30 21:01:35 volumio volumio[1192]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 30 21:01:35 volumio volumio[1192]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 30 21:01:35 volumio sudo[5262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 30 21:01:35 volumio sudo[5262]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:01:35 volumio sudo[5262]: pam_unix(sudo:session): session closed for user root Aug 30 21:01:35 volumio volumio[1192]: info: peppymeterbasic Daemon Stop Aug 30 21:01:36 volumio go-librespot[1526]: time="2024-08-30T21:01:36Z" level=debug msg="update volume to 64880/65535" Aug 30 21:01:36 volumio go-librespot[1526]: time="2024-08-30T21:01:36Z" level=debug msg="put connect state because VOLUME_CHANGED" Aug 30 21:01:36 volumio go-librespot[1526]: time="2024-08-30T21:01:36Z" level=trace msg="emitting websocket event: volume" Aug 30 21:01:36 volumio volumio[1192]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Aug 30 21:01:36 volumio volumio[1192]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Aug 30 21:01:36 volumio volumio[1192]: info: Setting Volumio Volume from Spotify: 100 Aug 30 21:01:36 volumio volumio[1192]: info: VolumeController::SetAlsaVolume100 Aug 30 21:01:36 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:01:36 volumio volumio[1192]: info: CoreStateMachine::pushState Aug 30 21:01:36 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 21:01:36 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState Aug 30 21:01:36 volumio volumio[1192]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 30 21:01:36 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:36 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 30 21:01:36 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:36 volumio volumio[1192]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 30 21:01:36 volumio sudo[5266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 30 21:01:36 volumio sudo[5266]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:01:36 volumio sudo[5266]: pam_unix(sudo:session): session closed for user root Aug 30 21:01:36 volumio volumio[1192]: info: peppymeterbasic Daemon Stop Aug 30 21:01:36 volumio go-librespot[1526]: time="2024-08-30T21:01:36Z" level=debug msg="update volume to 60948/65535" Aug 30 21:01:36 volumio volumio[1192]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Aug 30 21:01:36 volumio volumio[1192]: info: Sending Spotify command with payload to local API: /player/volume Aug 30 21:01:36 volumio volumio[1192]: error: Failed to send command to Spotify local API: /player/volume: Error: Bad Request Aug 30 21:01:36 volumio go-librespot[1526]: time="2024-08-30T21:01:36Z" level=debug msg="put connect state because VOLUME_CHANGED" Aug 30 21:01:36 volumio go-librespot[1526]: time="2024-08-30T21:01:36Z" level=trace msg="emitting websocket event: volume" Aug 30 21:01:36 volumio volumio[1192]: SPOTIFY: received: {"type":"volume","data":{"value":94,"max":100}} Aug 30 21:01:36 volumio volumio[1192]: SPOTIFY: RECEIVED SPOTIFY VOLUME 94 Aug 30 21:01:36 volumio volumio[1192]: info: Setting Volumio Volume from Spotify: 94 Aug 30 21:01:36 volumio volumio[1192]: info: VolumeController::SetAlsaVolume94 Aug 30 21:01:36 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:01:36 volumio volumio[1192]: info: CoreStateMachine::pushState Aug 30 21:01:36 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 21:01:36 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState Aug 30 21:01:36 volumio volumio[1192]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 30 21:01:36 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:36 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 30 21:01:36 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:36 volumio volumio[1192]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Aug 30 21:01:36 volumio sudo[5272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 30 21:01:36 volumio sudo[5272]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:01:36 volumio sudo[5272]: pam_unix(sudo:session): session closed for user root Aug 30 21:01:36 volumio volumio[1192]: info: peppymeterbasic Daemon Stop Aug 30 21:01:37 volumio go-librespot[1526]: time="2024-08-30T21:01:37Z" level=debug msg="update volume to 56360/65535" Aug 30 21:01:37 volumio go-librespot[1526]: time="2024-08-30T21:01:37Z" level=debug msg="put connect state because VOLUME_CHANGED" Aug 30 21:01:37 volumio go-librespot[1526]: time="2024-08-30T21:01:37Z" level=trace msg="emitting websocket event: volume" Aug 30 21:01:37 volumio volumio[1192]: SPOTIFY: received: {"type":"volume","data":{"value":86,"max":100}} Aug 30 21:01:37 volumio volumio[1192]: SPOTIFY: RECEIVED SPOTIFY VOLUME 86 Aug 30 21:01:37 volumio volumio[1192]: info: Setting Volumio Volume from Spotify: 86 Aug 30 21:01:37 volumio volumio[1192]: info: VolumeController::SetAlsaVolume86 Aug 30 21:01:37 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:01:37 volumio volumio[1192]: info: CoreStateMachine::pushState Aug 30 21:01:37 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 21:01:37 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState Aug 30 21:01:37 volumio volumio[1192]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 30 21:01:37 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:37 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 30 21:01:37 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:37 volumio volumio[1192]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 30 21:01:37 volumio sudo[5276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 30 21:01:37 volumio sudo[5276]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:01:37 volumio sudo[5276]: pam_unix(sudo:session): session closed for user root Aug 30 21:01:37 volumio volumio[1192]: info: peppymeterbasic Daemon Stop Aug 30 21:01:37 volumio go-librespot[1526]: time="2024-08-30T21:01:37Z" level=debug msg="update volume to 53739/65535" Aug 30 21:01:37 volumio go-librespot[1526]: time="2024-08-30T21:01:37Z" level=debug msg="put connect state because VOLUME_CHANGED" Aug 30 21:01:37 volumio go-librespot[1526]: time="2024-08-30T21:01:37Z" level=trace msg="emitting websocket event: volume" Aug 30 21:01:37 volumio volumio[1192]: SPOTIFY: received: {"type":"volume","data":{"value":83,"max":100}} Aug 30 21:01:37 volumio volumio[1192]: SPOTIFY: RECEIVED SPOTIFY VOLUME 83 Aug 30 21:01:37 volumio volumio[1192]: info: Setting Volumio Volume from Spotify: 83 Aug 30 21:01:37 volumio volumio[1192]: info: VolumeController::SetAlsaVolume83 Aug 30 21:01:37 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:01:37 volumio volumio[1192]: info: CoreStateMachine::pushState Aug 30 21:01:37 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 21:01:37 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState Aug 30 21:01:37 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:37 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 30 21:01:37 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:37 volumio volumio[1192]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 30 21:01:37 volumio volumio[1192]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Aug 30 21:01:37 volumio sudo[5280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 30 21:01:37 volumio sudo[5280]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:01:37 volumio sudo[5280]: pam_unix(sudo:session): session closed for user root Aug 30 21:01:37 volumio volumio[1192]: info: peppymeterbasic Daemon Stop Aug 30 21:01:37 volumio go-librespot[1526]: time="2024-08-30T21:01:37Z" level=debug msg="update volume to 48496/65535" Aug 30 21:01:37 volumio go-librespot[1526]: time="2024-08-30T21:01:37Z" level=debug msg="put connect state because VOLUME_CHANGED" Aug 30 21:01:37 volumio go-librespot[1526]: time="2024-08-30T21:01:37Z" level=trace msg="emitting websocket event: volume" Aug 30 21:01:37 volumio volumio[1192]: SPOTIFY: received: {"type":"volume","data":{"value":75,"max":100}} Aug 30 21:01:37 volumio volumio[1192]: SPOTIFY: RECEIVED SPOTIFY VOLUME 75 Aug 30 21:01:37 volumio volumio[1192]: info: Setting Volumio Volume from Spotify: 75 Aug 30 21:01:37 volumio volumio[1192]: info: VolumeController::SetAlsaVolume75 Aug 30 21:01:38 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:01:38 volumio volumio[1192]: info: CoreStateMachine::pushState Aug 30 21:01:38 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 21:01:38 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState Aug 30 21:01:38 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:38 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 30 21:01:38 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:38 volumio volumio[1192]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 30 21:01:38 volumio volumio[1192]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Aug 30 21:01:38 volumio sudo[5284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 30 21:01:38 volumio sudo[5284]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:01:38 volumio sudo[5284]: pam_unix(sudo:session): session closed for user root Aug 30 21:01:38 volumio volumio[1192]: info: peppymeterbasic Daemon Stop Aug 30 21:01:38 volumio go-librespot[1526]: time="2024-08-30T21:01:38Z" level=debug msg="update volume to 43253/65535" Aug 30 21:01:38 volumio go-librespot[1526]: time="2024-08-30T21:01:38Z" level=debug msg="put connect state because VOLUME_CHANGED" Aug 30 21:01:38 volumio go-librespot[1526]: time="2024-08-30T21:01:38Z" level=trace msg="emitting websocket event: volume" Aug 30 21:01:38 volumio volumio[1192]: SPOTIFY: received: {"type":"volume","data":{"value":66,"max":100}} Aug 30 21:01:38 volumio volumio[1192]: SPOTIFY: RECEIVED SPOTIFY VOLUME 66 Aug 30 21:01:38 volumio volumio[1192]: info: Setting Volumio Volume from Spotify: 66 Aug 30 21:01:38 volumio volumio[1192]: info: VolumeController::SetAlsaVolume66 Aug 30 21:01:38 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:01:38 volumio volumio[1192]: info: CoreStateMachine::pushState Aug 30 21:01:38 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 21:01:38 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState Aug 30 21:01:38 volumio volumio[1192]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 30 21:01:38 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:38 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 30 21:01:38 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:38 volumio volumio[1192]: SPOTIFY: RECEIVED VOLUMIO VOLUME 66 Aug 30 21:01:38 volumio sudo[5288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 30 21:01:38 volumio sudo[5288]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:01:38 volumio sudo[5288]: pam_unix(sudo:session): session closed for user root Aug 30 21:01:38 volumio volumio[1192]: info: peppymeterbasic Daemon Stop Aug 30 21:01:38 volumio go-librespot[1526]: time="2024-08-30T21:01:38Z" level=debug msg="update volume to 38010/65535" Aug 30 21:01:38 volumio go-librespot[1526]: time="2024-08-30T21:01:38Z" level=debug msg="put connect state because VOLUME_CHANGED" Aug 30 21:01:38 volumio go-librespot[1526]: time="2024-08-30T21:01:38Z" level=trace msg="emitting websocket event: volume" Aug 30 21:01:38 volumio volumio[1192]: SPOTIFY: received: {"type":"volume","data":{"value":58,"max":100}} Aug 30 21:01:38 volumio volumio[1192]: SPOTIFY: RECEIVED SPOTIFY VOLUME 58 Aug 30 21:01:38 volumio volumio[1192]: info: Setting Volumio Volume from Spotify: 58 Aug 30 21:01:38 volumio volumio[1192]: info: VolumeController::SetAlsaVolume58 Aug 30 21:01:38 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:01:38 volumio volumio[1192]: info: CoreStateMachine::pushState Aug 30 21:01:38 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 21:01:38 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState Aug 30 21:01:38 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:38 volumio volumio[1192]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 30 21:01:38 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 30 21:01:38 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:38 volumio volumio[1192]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Aug 30 21:01:38 volumio sudo[5292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 30 21:01:38 volumio sudo[5292]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:01:38 volumio sudo[5292]: pam_unix(sudo:session): session closed for user root Aug 30 21:01:38 volumio volumio[1192]: info: peppymeterbasic Daemon Stop Aug 30 21:01:39 volumio go-librespot[1526]: time="2024-08-30T21:01:39Z" level=debug msg="update volume to 32767/65535" Aug 30 21:01:39 volumio go-librespot[1526]: time="2024-08-30T21:01:39Z" level=debug msg="put connect state because VOLUME_CHANGED" Aug 30 21:01:39 volumio go-librespot[1526]: time="2024-08-30T21:01:39Z" level=trace msg="emitting websocket event: volume" Aug 30 21:01:39 volumio volumio[1192]: SPOTIFY: received: {"type":"volume","data":{"value":50,"max":100}} Aug 30 21:01:39 volumio volumio[1192]: SPOTIFY: RECEIVED SPOTIFY VOLUME 50 Aug 30 21:01:39 volumio volumio[1192]: info: Setting Volumio Volume from Spotify: 50 Aug 30 21:01:39 volumio volumio[1192]: info: VolumeController::SetAlsaVolume50 Aug 30 21:01:39 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:01:39 volumio volumio[1192]: info: CoreStateMachine::pushState Aug 30 21:01:39 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 21:01:39 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState Aug 30 21:01:39 volumio volumio[1192]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 30 21:01:39 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:39 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 30 21:01:39 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:39 volumio volumio[1192]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Aug 30 21:01:39 volumio sudo[5296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 30 21:01:39 volumio sudo[5296]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:01:39 volumio sudo[5296]: pam_unix(sudo:session): session closed for user root Aug 30 21:01:39 volumio volumio[1192]: info: peppymeterbasic Daemon Stop Aug 30 21:01:39 volumio go-librespot[1526]: time="2024-08-30T21:01:39Z" level=debug msg="update volume to 30146/65535" Aug 30 21:01:39 volumio go-librespot[1526]: time="2024-08-30T21:01:39Z" level=debug msg="put connect state because VOLUME_CHANGED" Aug 30 21:01:39 volumio go-librespot[1526]: time="2024-08-30T21:01:39Z" level=trace msg="emitting websocket event: volume" Aug 30 21:01:39 volumio volumio[1192]: SPOTIFY: received: {"type":"volume","data":{"value":46,"max":100}} Aug 30 21:01:39 volumio volumio[1192]: SPOTIFY: RECEIVED SPOTIFY VOLUME 46 Aug 30 21:01:39 volumio volumio[1192]: info: Setting Volumio Volume from Spotify: 46 Aug 30 21:01:39 volumio volumio[1192]: info: VolumeController::SetAlsaVolume46 Aug 30 21:01:39 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:01:39 volumio volumio[1192]: info: CoreStateMachine::pushState Aug 30 21:01:39 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 21:01:39 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState Aug 30 21:01:39 volumio volumio[1192]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 30 21:01:39 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:39 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 30 21:01:39 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:39 volumio volumio[1192]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Aug 30 21:01:39 volumio sudo[5300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 30 21:01:39 volumio sudo[5300]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:01:39 volumio sudo[5300]: pam_unix(sudo:session): session closed for user root Aug 30 21:01:39 volumio volumio[1192]: info: peppymeterbasic Daemon Stop Aug 30 21:01:39 volumio go-librespot[1526]: time="2024-08-30T21:01:39Z" level=debug msg="update volume to 22282/65535" Aug 30 21:01:39 volumio go-librespot[1526]: time="2024-08-30T21:01:39Z" level=debug msg="put connect state because VOLUME_CHANGED" Aug 30 21:01:39 volumio go-librespot[1526]: time="2024-08-30T21:01:39Z" level=trace msg="emitting websocket event: volume" Aug 30 21:01:39 volumio volumio[1192]: SPOTIFY: received: {"type":"volume","data":{"value":35,"max":100}} Aug 30 21:01:39 volumio volumio[1192]: SPOTIFY: RECEIVED SPOTIFY VOLUME 35 Aug 30 21:01:39 volumio volumio[1192]: info: Setting Volumio Volume from Spotify: 35 Aug 30 21:01:39 volumio volumio[1192]: info: VolumeController::SetAlsaVolume35 Aug 30 21:01:39 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:01:39 volumio volumio[1192]: info: CoreStateMachine::pushState Aug 30 21:01:39 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 21:01:39 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState Aug 30 21:01:39 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:39 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 30 21:01:39 volumio volumio[1192]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 30 21:01:39 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:39 volumio volumio[1192]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Aug 30 21:01:39 volumio sudo[5304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 30 21:01:39 volumio sudo[5304]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:01:39 volumio sudo[5304]: pam_unix(sudo:session): session closed for user root Aug 30 21:01:39 volumio volumio[1192]: info: peppymeterbasic Daemon Stop Aug 30 21:01:40 volumio go-librespot[1526]: time="2024-08-30T21:01:40Z" level=debug msg="update volume to 19660/65535" Aug 30 21:01:40 volumio go-librespot[1526]: time="2024-08-30T21:01:40Z" level=debug msg="put connect state because VOLUME_CHANGED" Aug 30 21:01:40 volumio go-librespot[1526]: time="2024-08-30T21:01:40Z" level=trace msg="emitting websocket event: volume" Aug 30 21:01:40 volumio volumio[1192]: SPOTIFY: received: {"type":"volume","data":{"value":30,"max":100}} Aug 30 21:01:40 volumio volumio[1192]: SPOTIFY: RECEIVED SPOTIFY VOLUME 30 Aug 30 21:01:40 volumio volumio[1192]: info: Setting Volumio Volume from Spotify: 30 Aug 30 21:01:40 volumio volumio[1192]: info: VolumeController::SetAlsaVolume30 Aug 30 21:01:40 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:01:40 volumio volumio[1192]: info: CoreStateMachine::pushState Aug 30 21:01:40 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 21:01:40 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState Aug 30 21:01:40 volumio volumio[1192]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 30 21:01:40 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:40 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 30 21:01:40 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:40 volumio volumio[1192]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Aug 30 21:01:40 volumio sudo[5308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 30 21:01:40 volumio sudo[5308]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:01:40 volumio sudo[5308]: pam_unix(sudo:session): session closed for user root Aug 30 21:01:40 volumio volumio[1192]: info: peppymeterbasic Daemon Stop Aug 30 21:01:40 volumio go-librespot[1526]: time="2024-08-30T21:01:40Z" level=debug msg="update volume to 15728/65535" Aug 30 21:01:40 volumio go-librespot[1526]: time="2024-08-30T21:01:40Z" level=debug msg="put connect state because VOLUME_CHANGED" Aug 30 21:01:40 volumio go-librespot[1526]: time="2024-08-30T21:01:40Z" level=trace msg="emitting websocket event: volume" Aug 30 21:01:40 volumio volumio[1192]: SPOTIFY: received: {"type":"volume","data":{"value":24,"max":100}} Aug 30 21:01:40 volumio volumio[1192]: SPOTIFY: RECEIVED SPOTIFY VOLUME 24 Aug 30 21:01:40 volumio volumio[1192]: info: Setting Volumio Volume from Spotify: 24 Aug 30 21:01:40 volumio volumio[1192]: info: VolumeController::SetAlsaVolume24 Aug 30 21:01:40 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:01:40 volumio volumio[1192]: info: CoreStateMachine::pushState Aug 30 21:01:40 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 21:01:40 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState Aug 30 21:01:40 volumio volumio[1192]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 30 21:01:40 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:40 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 30 21:01:40 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:01:40 volumio volumio[1192]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Aug 30 21:01:40 volumio sudo[5312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 30 21:01:40 volumio sudo[5312]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:01:40 volumio sudo[5312]: pam_unix(sudo:session): session closed for user root Aug 30 21:01:40 volumio volumio[1192]: info: peppymeterbasic Daemon Stop Aug 30 21:01:48 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 21:01:48 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 21:01:48 volumio volumio[1192]: info: Discovery: Getting this device information Aug 30 21:01:48 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:01:48 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 21:01:48 volumio volumio[1192]: verbose: New Socket.io Connection to 192.168.31.82:3000 from 192.168.31.69 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Aug 30 21:01:48 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:01:48 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 21:01:48 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 21:01:51 volumio sudo[5319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 21:01:51 volumio sudo[5319]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:01:51 volumio sudo[5319]: pam_unix(sudo:session): session closed for user root Aug 30 21:01:51 volumio sudo[5322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 21:01:51 volumio sudo[5322]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:01:51 volumio sudo[5322]: pam_unix(sudo:session): session closed for user root Aug 30 21:01:51 volumio volumio[1192]: verbose: New Socket.io Connection to 192.168.31.82 from 192.168.31.69 UA: Mozilla/5.0 (Linux; Android 14; 24030PN60G Build/UKQ1.231003.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/128.0.6613.88 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Aug 30 21:01:52 volumio sudo[5326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 21:01:52 volumio sudo[5326]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:01:52 volumio sudo[5326]: pam_unix(sudo:session): session closed for user root Aug 30 21:01:52 volumio sudo[5329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 21:01:52 volumio sudo[5329]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:01:52 volumio sudo[5329]: pam_unix(sudo:session): session closed for user root Aug 30 21:01:52 volumio volumio[1192]: verbose: New Socket.io Connection to 192.168.31.82 from 192.168.31.69 UA: Mozilla/5.0 (Linux; Android 14; 24030PN60G Build/UKQ1.231003.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/128.0.6613.88 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Aug 30 21:01:52 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 21:01:52 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 30 21:01:52 volumio volumio[1192]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 21:01:52 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 21:01:52 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:01:52 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 21:01:52 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 21:01:52 volumio volumio[1192]: info: Received Get System Info Aug 30 21:01:52 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 21:01:52 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 21:01:52 volumio volumio[1192]: info: Discovery: Getting this device information Aug 30 21:01:52 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:01:52 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 21:01:52 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:01:52 volumio volumio[1192]: info: Listing playlists Aug 30 21:01:52 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 30 21:01:52 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 21:01:52 volumio volumio[1192]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 21:01:54 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 21:01:54 volumio volumio[1192]: info: Received Get System Info Aug 30 21:01:54 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 21:01:54 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 21:01:54 volumio volumio[1192]: info: Discovery: Getting this device information Aug 30 21:01:54 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:01:54 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 21:01:54 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 30 21:01:54 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 30 21:02:00 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 21:02:00 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 30 21:02:00 volumio volumio[1192]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 21:02:00 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 21:02:00 volumio volumio[1192]: info: Received Get System Info Aug 30 21:02:00 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 21:02:00 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 21:02:00 volumio volumio[1192]: info: Discovery: Getting this device information Aug 30 21:02:00 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:02:00 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 21:02:00 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 21:02:00 volumio volumio[1192]: info: Received Get System Info Aug 30 21:02:00 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 21:02:00 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 21:02:00 volumio volumio[1192]: info: Discovery: Getting this device information Aug 30 21:02:00 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:02:00 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 21:02:00 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 21:02:00 volumio volumio[1192]: info: Received Get System Info Aug 30 21:02:00 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 21:02:00 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 21:02:00 volumio volumio[1192]: info: Discovery: Getting this device information Aug 30 21:02:00 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:02:00 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 21:02:02 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 30 21:02:10 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 21:02:10 volumio volumio[1192]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 30 21:02:10 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 30 21:02:10 volumio volumio[1192]: info: Received Get System Version Aug 30 21:02:10 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 21:02:10 volumio volumio[1192]: info: Received Get System Info Aug 30 21:02:10 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 21:02:10 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 21:02:10 volumio volumio[1192]: info: Discovery: Getting this device information Aug 30 21:02:10 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:02:10 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 21:02:12 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 21:02:12 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 21:02:12 volumio volumio[1192]: info: Discovery: Getting this device information Aug 30 21:02:12 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:02:12 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 21:02:14 volumio volumio[1192]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 21:02:14 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 30 21:02:14 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:02:14 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 30 21:02:14 volumio volumio[1192]: info: Getting Alsa Cards List without I2S DAC Aug 30 21:02:14 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 30 21:02:14 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:02:14 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:02:14 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 30 21:02:14 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:02:14 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 21:02:14 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 21:02:14 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 30 21:02:14 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 30 21:02:24 volumio volumio[1192]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 30 21:02:24 volumio volumio[1192]: info: Preparing to save Alsa Options, stopping services first Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::volumioPause Aug 30 21:02:24 volumio volumio[1192]: info: CoreStateMachine::pause Aug 30 21:02:24 volumio volumio[1192]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI 0 Out"},"i2s":true,"i2sid":{"value":"hifiberry-dac","label":"HiFiBerry DAC"}} Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:02:24 volumio volumio[1192]: info: Enabling I2S DAC: HiFiBerry DAC Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:02:24 volumio sudo[5387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Aug 30 21:02:24 volumio sudo[5387]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:02:24 volumio sudo[5387]: pam_unix(sudo:session): session closed for user root Aug 30 21:02:24 volumio volumio[1192]: info: No Overlays Loaded Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:02:24 volumio sudo[5390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-dac Aug 30 21:02:24 volumio sudo[5390]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:02:24 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@120000/rp1/i2s@a0000/status Aug 30 21:02:24 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Aug 30 21:02:24 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Aug 30 21:02:24 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Aug 30 21:02:24 volumio sudo[5390]: pam_unix(sudo:session): session closed for user root Aug 30 21:02:24 volumio volumio[1192]: warn: Unable to locate the device HDMI 0 Out a reboot may be required. Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:02:24 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:02:24 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 30 21:02:24 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:02:24 volumio volumio[1192]: info: Setting default mixerSoftMaster for Softvolume device Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 30 21:02:24 volumio volumio[1192]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 30 21:02:24 volumio volumio[1192]: info: Disabling external Volume Control Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 21:02:24 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:02:24 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 30 21:02:24 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:02:24 volumio volumio[1192]: info: Enable softmixer device for audio device undefined Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Aug 30 21:02:24 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:02:24 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 30 21:02:24 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:02:24 volumio volumio[1192]: info: I2S Param [object Object] successfully enabled Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:02:24 volumio volumio[1192]: info: CoreStateMachine::pushState Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::volumioPushState Aug 30 21:02:24 volumio volumio[1192]: info: Software Volume ALSA configuration written Aug 30 21:02:24 volumio volumio[1192]: info: Preparing to generate the ALSA configuration file Aug 30 21:02:24 volumio volumio[1192]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 30 21:02:24 volumio volumio[1192]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 30 21:02:24 volumio volumio[1192]: SPOTIFY: SPOTIFY VOLUME 24 Aug 30 21:02:24 volumio volumio[1192]: SPOTIFY: VOLUMIO VOLUME 100 Aug 30 21:02:24 volumio volumio[1192]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 30 21:02:24 volumio volumio[1192]: info: Setting Spotify Volume from Volumio: 100 Aug 30 21:02:24 volumio sudo[5418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 30 21:02:24 volumio sudo[5418]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:02:24 volumio sudo[5418]: pam_unix(sudo:session): session closed for user root Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 30 21:02:24 volumio volumio[1192]: info: Getting Alsa Cards List without I2S DAC Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:02:24 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:02:24 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 30 21:02:24 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 30 21:02:24 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 30 21:02:24 volumio volumio[1192]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 30 21:02:24 volumio volumio[1192]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 30 21:02:24 volumio volumio[1192]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Aug 30 21:02:24 volumio volumio[1192]: info: Reading ALSA contributions from plugins. Aug 30 21:02:24 volumio volumio[1192]: info: peppymeterbasic Daemon Stop Aug 30 21:02:24 volumio volumio[1192]: info: Asound.conf file written Aug 30 21:02:24 volumio sudo[5429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 30 21:02:24 volumio sudo[5429]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:02:24 volumio sudo[5429]: pam_unix(sudo:session): session closed for user root Aug 30 21:02:25 volumio volumio[1192]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Volume:0' : Remote I/O error Aug 30 21:02:25 volumio volumio[1192]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Switch:0' : Remote I/O error Aug 30 21:02:25 volumio volumio[1192]: info: Output device has changed, restarting MPD Aug 30 21:02:25 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 30 21:02:25 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 30 21:02:25 volumio sudo[5435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 21:02:25 volumio sudo[5435]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:02:25 volumio sudo[5435]: pam_unix(sudo:session): session closed for user root Aug 30 21:02:25 volumio volumio[1192]: info: Output device has changed, restarting Shairport Sync Aug 30 21:02:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:02:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:02:25 volumio sudo[5438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 21:02:25 volumio sudo[5438]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:02:25 volumio systemd[1]: Stopping Music Player Daemon... Aug 30 21:02:25 volumio systemd[1]: mpd.service: Succeeded. Aug 30 21:02:25 volumio systemd[1]: Stopped Music Player Daemon. Aug 30 21:02:25 volumio volumio[1192]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 21:02:25 volumio volumio[1192]: info: Output device has changed, restarting MPD Aug 30 21:02:25 volumio sudo[5445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 21:02:25 volumio systemd[1]: Starting Music Player Daemon... Aug 30 21:02:25 volumio sudo[5445]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:02:25 volumio sudo[5445]: pam_unix(sudo:session): session closed for user root Aug 30 21:02:25 volumio volumio[1192]: info: Output device has changed, restarting Shairport Sync Aug 30 21:02:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:02:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:02:25 volumio sudo[5449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 21:02:25 volumio sudo[5449]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:02:25 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 30 21:02:25 volumio systemd[1]: mpd.service: Succeeded. Aug 30 21:02:25 volumio systemd[1]: Stopped Music Player Daemon. Aug 30 21:02:25 volumio systemd[1]: Starting Music Player Daemon... Aug 30 21:02:25 volumio volumio[1192]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 21:02:25 volumio volumio[1192]: info: MPD Permissions set Aug 30 21:02:25 volumio volumio[1192]: info: MPD Permissions set Aug 30 21:02:25 volumio volumio[1192]: info: CoreCommandRouter::volumioGetState Aug 30 21:02:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:02:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:02:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:02:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:02:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:02:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:02:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:02:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:02:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:02:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:02:25 volumio sudo[5454]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 21:02:25 volumio sudo[5454]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:02:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:02:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:02:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:02:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:02:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:02:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:02:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:02:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:02:25 volumio sudo[5454]: pam_unix(sudo:session): session closed for user root Aug 30 21:02:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 21:02:25 volumio volumio[1192]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 21:02:25 volumio volumio[1192]: info: Starting Shairport Sync Aug 30 21:02:25 volumio volumio[1192]: info: Starting Shairport Sync Aug 30 21:02:25 volumio sudo[5466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 21:02:25 volumio sudo[5466]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:02:25 volumio sudo[5469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 21:02:25 volumio sudo[5469]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 21:02:25 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 30 21:02:25 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 30 21:02:25 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 30 21:02:25 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 30 21:02:25 volumio sudo[5466]: pam_unix(sudo:session): session closed for user root Aug 30 21:02:25 volumio volumio[1192]: info: Shairport-Sync Started Aug 30 21:02:25 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 30 21:02:25 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 30 21:02:25 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 30 21:02:25 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 30 21:02:25 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 30 21:02:25 volumio sudo[5469]: pam_unix(sudo:session): session closed for user root Aug 30 21:02:25 volumio volumio[1192]: info: Shairport-Sync Started Aug 30 21:02:25 volumio mpd[5461]: Aug 30 21:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 30 21:02:25 volumio systemd[1]: Started Music Player Daemon. Aug 30 21:02:25 volumio sudo[5449]: pam_unix(sudo:session): session closed for user root Aug 30 21:02:25 volumio sudo[5438]: pam_unix(sudo:session): session closed for user root Aug 30 21:02:25 volumio volumio[1192]: error: MPD error: The expression evaluated to a falsy value: Aug 30 21:02:25 volumio volumio[1192]: assert.ok(self.idling) Aug 30 21:02:25 volumio volumio[1192]: error: The expression evaluated to a falsy value: Aug 30 21:02:25 volumio volumio[1192]: assert.ok(self.idling) Aug 30 21:02:25 volumio volumio[1192]: error: updateQueue error: null Aug 30 21:02:26 volumio volumio[1192]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Aug 30 21:02:26 volumio volumio[1192]: info: Sending Spotify command with payload to local API: /player/volume Aug 30 21:02:26 volumio volumio[1192]: error: Failed to send command to Spotify local API: /player/volume: Error: Bad Request Aug 30 21:02:26 volumio volumio[1192]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Aug 30 21:02:26 volumio volumio[1192]: info: PLUGIN onReboot : networkfs Aug 30 21:02:26 volumio volumio[1192]: info: PLUGIN onReboot : audiophonicsonoff Aug 30 21:02:26 volumio volumio[1192]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 21:02:26 volumio volumio[1192]: TypeError: Cannot read property 'writeSync' of undefined Aug 30 21:02:26 volumio volumio[1192]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Aug 30 21:02:26 volumio volumio[1192]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Aug 30 21:02:26 volumio volumio[1192]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Aug 30 21:02:26 volumio volumio[1192]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Aug 30 21:02:26 volumio volumio[1192]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Aug 30 21:02:26 volumio volumio[1192]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Aug 30 21:02:26 volumio volumio[1192]: at CoreCommandRouter.reboot (/volumio/app/index.js:1331:22) Aug 30 21:02:26 volumio volumio[1192]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:864:33) Aug 30 21:02:26 volumio volumio[1192]: at Socket.emit (events.js:315:20) Aug 30 21:02:26 volumio volumio[1192]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 30 21:02:26 volumio volumio[1192]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 30 21:02:26 volumio volumio[1192]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 21:02:26 volumio sudo[5487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-30 21:01 Aug 30 21:02:26 volumio sudo[5487]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"