-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2025-04-29 23:04:27 CEST. -- Apr 29 23:03:06 beolab-8000 ntpd[738]: 94.198.159.16 local addr 192.168.2.56 -> Apr 29 23:03:08 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 29 23:03:08 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 29 23:03:08 beolab-8000 volumio[6777]: info: Discovery: Getting this device information Apr 29 23:03:08 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioGetState Apr 29 23:03:08 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 29 23:03:10 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:10+02:00" level=debug msg="fetched chunk 5/13, size: 524288" uri="spotify:track:0kRMltaFIzcXkSbWZVBgMM" Apr 29 23:03:15 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:15+02:00" level=trace msg="sent dealer ping" Apr 29 23:03:15 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:15+02:00" level=trace msg="received dealer pong" Apr 29 23:03:26 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:26+02:00" level=debug msg="fetched chunk 6/13, size: 524288" uri="spotify:track:0kRMltaFIzcXkSbWZVBgMM" Apr 29 23:03:38 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 29 23:03:38 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 29 23:03:38 beolab-8000 volumio[6777]: info: Discovery: Getting this device information Apr 29 23:03:38 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioGetState Apr 29 23:03:38 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 29 23:03:40 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:40+02:00" level=debug msg="fetched chunk 7/13, size: 524288" uri="spotify:track:0kRMltaFIzcXkSbWZVBgMM" Apr 29 23:03:40 beolab-8000 volumio[6777]: info: CALLMETHOD: system_hardware gpio-buttons saveConfig [object Object] Apr 29 23:03:40 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: gpio-buttons , saveConfig Apr 29 23:03:40 beolab-8000 volumio[6777]: info: GPIO-Buttons: Destroying trigger 0 Apr 29 23:03:40 beolab-8000 volumio[6777]: info: GPIO-Buttons: Reading config and creating triggers... Apr 29 23:03:40 beolab-8000 volumio[6777]: info: GPIO-Buttons: playPause on pin 532 Apr 29 23:03:43 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPause Apr 29 23:03:43 beolab-8000 volumio[6777]: info: CoreStateMachine::pause Apr 29 23:03:43 beolab-8000 volumio[6777]: info: CoreStateMachine::stPlaybackTimer Apr 29 23:03:43 beolab-8000 volumio[6777]: info: CoreStateMachine::servicePause Apr 29 23:03:43 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePause Apr 29 23:03:43 beolab-8000 volumio[6777]: info: Spotify Received pause Apr 29 23:03:43 beolab-8000 volumio[6777]: SPOTIFY: SPOTIFY PAUSE Apr 29 23:03:43 beolab-8000 volumio[6777]: SPOTIFY: {"status":"play","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","codec":"ogg","seek":0,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":20,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Apr 29 23:03:43 beolab-8000 volumio[6777]: info: Sending Spotify command to local API: /player/pause Apr 29 23:03:43 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:43+02:00" level=debug msg="pause track at 64871ms" Apr 29 23:03:43 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:43+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:03:43 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:43+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:03:43 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:43+02:00" level=trace msg="emitting websocket event: paused" Apr 29 23:03:43 beolab-8000 volumio[6777]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","play_origin":"free-tier-artist"}} Apr 29 23:03:43 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:03:43 beolab-8000 volumio[6777]: SPOTIFY: {"status":"pause","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":65000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:03:43 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:03:43 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:03:43 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 23:03:43 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:03:43 beolab-8000 volumio[6777]: info: [ASDebug] CurState: pause PrevState: play Apr 29 23:03:43 beolab-8000 volumio[6777]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 29 23:03:43 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:03:44 beolab-8000 volumio[6777]: info: [ASDebug] Togle GPIO: OFF Apr 29 23:03:44 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioVolatilePlay Apr 29 23:03:44 beolab-8000 volumio[6777]: info: CoreStateMachine::volatilePlay Apr 29 23:03:44 beolab-8000 volumio[6777]: info: Spotify Play Apr 29 23:03:44 beolab-8000 volumio[6777]: info: Sending Spotify command to local API: /player/resume Apr 29 23:03:44 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:44+02:00" level=trace msg="seek to 64871ms (diff: 46ms, samples: 2860811, bytes: 2194990)" uri="spotify:track:0kRMltaFIzcXkSbWZVBgMM" Apr 29 23:03:44 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:44+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Apr 29 23:03:44 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:44+02:00" level=debug msg="resume track at 64825ms" Apr 29 23:03:44 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:44+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:03:44 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:44+02:00" level=trace msg="scheduling prefetch in 105s" Apr 29 23:03:45 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:45+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:03:45 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:45+02:00" level=trace msg="emitting websocket event: playing" Apr 29 23:03:45 beolab-8000 volumio[6777]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","play_origin":"free-tier-artist"}} Apr 29 23:03:45 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:03:45 beolab-8000 volumio[6777]: SPOTIFY: {"status":"play","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":65000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:03:45 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:03:45 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:03:45 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 23:03:45 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:03:45 beolab-8000 volumio[6777]: info: [ASDebug] CurState: play PrevState: pause Apr 29 23:03:45 beolab-8000 volumio[6777]: info: [ASDebug] Togle GPIO: ON Apr 29 23:03:45 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:03:45 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:03:45 beolab-8000 volumio[6777]: SPOTIFY: {"status":"play","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":65000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:03:45 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:03:45 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:03:45 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:03:45 beolab-8000 volumio[6777]: info: [ASDebug] CurState: play PrevState: play Apr 29 23:03:45 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:03:45 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:45+02:00" level=trace msg="sent dealer ping" Apr 29 23:03:45 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:45+02:00" level=trace msg="received dealer pong" Apr 29 23:03:46 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPause Apr 29 23:03:46 beolab-8000 volumio[6777]: info: CoreStateMachine::pause Apr 29 23:03:46 beolab-8000 volumio[6777]: info: CoreStateMachine::stPlaybackTimer Apr 29 23:03:46 beolab-8000 volumio[6777]: info: CoreStateMachine::servicePause Apr 29 23:03:46 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePause Apr 29 23:03:46 beolab-8000 volumio[6777]: info: Spotify Received pause Apr 29 23:03:46 beolab-8000 volumio[6777]: SPOTIFY: SPOTIFY PAUSE Apr 29 23:03:46 beolab-8000 volumio[6777]: SPOTIFY: {"status":"play","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","codec":"ogg","seek":65000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":20,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Apr 29 23:03:46 beolab-8000 volumio[6777]: info: Sending Spotify command to local API: /player/pause Apr 29 23:03:46 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:46+02:00" level=debug msg="pause track at 66821ms" Apr 29 23:03:47 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:47+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:03:47 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:47+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:03:47 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:47+02:00" level=trace msg="emitting websocket event: paused" Apr 29 23:03:47 beolab-8000 volumio[6777]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","play_origin":"free-tier-artist"}} Apr 29 23:03:47 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:03:47 beolab-8000 volumio[6777]: SPOTIFY: {"status":"pause","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":67000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:03:47 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:03:47 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:03:47 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 23:03:47 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:03:47 beolab-8000 volumio[6777]: info: [ASDebug] CurState: pause PrevState: play Apr 29 23:03:47 beolab-8000 volumio[6777]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 29 23:03:47 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:03:47 beolab-8000 volumio[6777]: info: [ASDebug] Togle GPIO: OFF Apr 29 23:03:48 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioVolatilePlay Apr 29 23:03:48 beolab-8000 volumio[6777]: info: CoreStateMachine::volatilePlay Apr 29 23:03:48 beolab-8000 volumio[6777]: info: Spotify Play Apr 29 23:03:48 beolab-8000 volumio[6777]: info: Sending Spotify command to local API: /player/resume Apr 29 23:03:48 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:48+02:00" level=trace msg="seek to 66821ms (diff: 80ms, samples: 2946806, bytes: 2264575)" uri="spotify:track:0kRMltaFIzcXkSbWZVBgMM" Apr 29 23:03:48 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:48+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Apr 29 23:03:48 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:48+02:00" level=debug msg="resume track at 66741ms" Apr 29 23:03:48 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:48+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:03:48 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:48+02:00" level=trace msg="scheduling prefetch in 103s" Apr 29 23:03:48 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:48+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:03:48 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:48+02:00" level=trace msg="emitting websocket event: playing" Apr 29 23:03:48 beolab-8000 volumio[6777]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","play_origin":"free-tier-artist"}} Apr 29 23:03:48 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:03:48 beolab-8000 volumio[6777]: SPOTIFY: {"status":"play","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":67000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:03:48 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:03:48 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:03:48 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 23:03:48 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:03:48 beolab-8000 volumio[6777]: info: [ASDebug] CurState: play PrevState: pause Apr 29 23:03:48 beolab-8000 volumio[6777]: info: [ASDebug] Togle GPIO: ON Apr 29 23:03:48 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:03:49 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:03:49 beolab-8000 volumio[6777]: SPOTIFY: {"status":"play","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":67000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:03:49 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:03:49 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:03:49 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:03:49 beolab-8000 volumio[6777]: info: [ASDebug] CurState: play PrevState: play Apr 29 23:03:49 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:03:49 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPause Apr 29 23:03:49 beolab-8000 volumio[6777]: info: CoreStateMachine::pause Apr 29 23:03:49 beolab-8000 volumio[6777]: info: CoreStateMachine::stPlaybackTimer Apr 29 23:03:49 beolab-8000 volumio[6777]: info: CoreStateMachine::servicePause Apr 29 23:03:49 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePause Apr 29 23:03:49 beolab-8000 volumio[6777]: info: Spotify Received pause Apr 29 23:03:49 beolab-8000 volumio[6777]: SPOTIFY: SPOTIFY PAUSE Apr 29 23:03:49 beolab-8000 volumio[6777]: SPOTIFY: {"status":"play","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","codec":"ogg","seek":67000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":20,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Apr 29 23:03:49 beolab-8000 volumio[6777]: info: Sending Spotify command to local API: /player/pause Apr 29 23:03:49 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:49+02:00" level=debug msg="pause track at 67942ms" Apr 29 23:03:49 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:49+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:03:49 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:49+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:03:49 beolab-8000 go-librespot[6978]: time="2025-04-29T23:03:49+02:00" level=trace msg="emitting websocket event: paused" Apr 29 23:03:49 beolab-8000 volumio[6777]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","play_origin":"free-tier-artist"}} Apr 29 23:03:49 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:03:49 beolab-8000 volumio[6777]: SPOTIFY: {"status":"pause","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:03:49 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:03:49 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:03:49 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 23:03:49 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:03:50 beolab-8000 volumio[6777]: info: [ASDebug] CurState: pause PrevState: play Apr 29 23:03:50 beolab-8000 volumio[6777]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 29 23:03:50 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:03:50 beolab-8000 volumio[6777]: info: [ASDebug] Togle GPIO: OFF Apr 29 23:03:58 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 29 23:03:58 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 29 23:04:00 beolab-8000 volumio[6777]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 23:04:00 beolab-8000 volumio[6777]: info: [ASDebug] Setting UI defaults Apr 29 23:04:00 beolab-8000 volumio[6777]: info: [ASDebug] Port: 9 Apr 29 23:04:00 beolab-8000 volumio[6777]: info: [ASDebug] Inverted: false Apr 29 23:04:00 beolab-8000 volumio[6777]: info: [ASDebug] Latched: false Apr 29 23:04:00 beolab-8000 volumio[6777]: info: [ASDebug] On pulse width: 500 Apr 29 23:04:00 beolab-8000 volumio[6777]: info: [ASDebug] Off pulse width: 500 Apr 29 23:04:05 beolab-8000 volumio[6777]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Apr 29 23:04:05 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Apr 29 23:04:05 beolab-8000 volumio[6777]: info: [ASDebug] Saving Settings: Port: 12 Apr 29 23:04:05 beolab-8000 volumio[6777]: info: [ASDebug] Saving Settings: Inverted: false Apr 29 23:04:05 beolab-8000 volumio[6777]: info: [ASDebug] Saving Settings: Delay: 720 Apr 29 23:04:05 beolab-8000 volumio[6777]: info: [ASDebug] Saving Settings: Latched: false Apr 29 23:04:05 beolab-8000 volumio[6777]: info: [ASDebug] Saving Settings: On Pulse width: 500 Apr 29 23:04:05 beolab-8000 volumio[6777]: info: [ASDebug] Saving Settings: Off Pulse width: 500 Apr 29 23:04:07 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioVolatilePlay Apr 29 23:04:07 beolab-8000 volumio[6777]: info: CoreStateMachine::volatilePlay Apr 29 23:04:07 beolab-8000 volumio[6777]: info: Spotify Play Apr 29 23:04:07 beolab-8000 volumio[6777]: info: Sending Spotify command to local API: /player/resume Apr 29 23:04:07 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:07+02:00" level=trace msg="seek to 67942ms (diff: 147ms, samples: 2996242, bytes: 2304475)" uri="spotify:track:0kRMltaFIzcXkSbWZVBgMM" Apr 29 23:04:07 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:07+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Apr 29 23:04:07 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:07+02:00" level=debug msg="resume track at 67795ms" Apr 29 23:04:07 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:07+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:07 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:07+02:00" level=trace msg="scheduling prefetch in 102s" Apr 29 23:04:07 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:07+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:07 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:07+02:00" level=trace msg="emitting websocket event: playing" Apr 29 23:04:07 beolab-8000 volumio[6777]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","play_origin":"free-tier-artist"}} Apr 29 23:04:07 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:04:07 beolab-8000 volumio[6777]: SPOTIFY: {"status":"play","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:04:07 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:04:07 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:04:07 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 23:04:07 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:04:07 beolab-8000 volumio[6777]: info: [ASDebug] CurState: play PrevState: pause Apr 29 23:04:07 beolab-8000 volumio[6777]: info: [ASDebug] Togle GPIO: ON Apr 29 23:04:07 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:04:07 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioGetState Apr 29 23:04:07 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPause Apr 29 23:04:07 beolab-8000 volumio[6777]: info: CoreStateMachine::pause Apr 29 23:04:07 beolab-8000 volumio[6777]: info: CoreStateMachine::stPlaybackTimer Apr 29 23:04:07 beolab-8000 volumio[6777]: info: CoreStateMachine::servicePause Apr 29 23:04:07 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePause Apr 29 23:04:07 beolab-8000 volumio[6777]: info: Spotify Received pause Apr 29 23:04:07 beolab-8000 volumio[6777]: SPOTIFY: SPOTIFY PAUSE Apr 29 23:04:07 beolab-8000 volumio[6777]: SPOTIFY: {"status":"play","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","codec":"ogg","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":20,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Apr 29 23:04:07 beolab-8000 volumio[6777]: info: Sending Spotify command to local API: /player/pause Apr 29 23:04:07 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:07+02:00" level=debug msg="pause track at 67825ms" Apr 29 23:04:07 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:07+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:07 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:07+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:07 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:07+02:00" level=trace msg="emitting websocket event: paused" Apr 29 23:04:07 beolab-8000 volumio[6777]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","play_origin":"free-tier-artist"}} Apr 29 23:04:07 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:04:07 beolab-8000 volumio[6777]: SPOTIFY: {"status":"pause","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:04:07 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:04:07 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:04:07 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 23:04:07 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:04:07 beolab-8000 volumio[6777]: info: [ASDebug] CurState: pause PrevState: play Apr 29 23:04:07 beolab-8000 volumio[6777]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 29 23:04:07 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:04:07 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:04:07 beolab-8000 volumio[6777]: SPOTIFY: {"status":"pause","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:04:07 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:04:07 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:04:07 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:04:07 beolab-8000 volumio[6777]: info: [ASDebug] CurState: pause PrevState: play Apr 29 23:04:07 beolab-8000 volumio[6777]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 29 23:04:07 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:04:08 beolab-8000 volumio[6777]: info: [ASDebug] Togle GPIO: OFF Apr 29 23:04:08 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioVolatilePlay Apr 29 23:04:08 beolab-8000 volumio[6777]: info: CoreStateMachine::volatilePlay Apr 29 23:04:08 beolab-8000 volumio[6777]: info: Spotify Play Apr 29 23:04:08 beolab-8000 volumio[6777]: info: Sending Spotify command to local API: /player/resume Apr 29 23:04:08 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:08+02:00" level=trace msg="seek to 67825ms (diff: 163ms, samples: 2991082, bytes: 2300310)" uri="spotify:track:0kRMltaFIzcXkSbWZVBgMM" Apr 29 23:04:08 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:08+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Apr 29 23:04:08 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:08+02:00" level=debug msg="resume track at 67662ms" Apr 29 23:04:08 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:08+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:08 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:08+02:00" level=trace msg="scheduling prefetch in 102s" Apr 29 23:04:08 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:08+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:08 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:08+02:00" level=trace msg="emitting websocket event: playing" Apr 29 23:04:08 beolab-8000 volumio[6777]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","play_origin":"free-tier-artist"}} Apr 29 23:04:08 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:04:08 beolab-8000 volumio[6777]: SPOTIFY: {"status":"play","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:04:08 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:04:08 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:04:08 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 23:04:08 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:04:08 beolab-8000 volumio[6777]: info: [ASDebug] CurState: play PrevState: pause Apr 29 23:04:08 beolab-8000 volumio[6777]: info: [ASDebug] Togle GPIO: ON Apr 29 23:04:08 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:04:08 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioGetState Apr 29 23:04:08 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPause Apr 29 23:04:08 beolab-8000 volumio[6777]: info: CoreStateMachine::pause Apr 29 23:04:08 beolab-8000 volumio[6777]: info: CoreStateMachine::stPlaybackTimer Apr 29 23:04:08 beolab-8000 volumio[6777]: info: CoreStateMachine::servicePause Apr 29 23:04:08 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePause Apr 29 23:04:08 beolab-8000 volumio[6777]: info: Spotify Received pause Apr 29 23:04:08 beolab-8000 volumio[6777]: SPOTIFY: SPOTIFY PAUSE Apr 29 23:04:08 beolab-8000 volumio[6777]: SPOTIFY: {"status":"play","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","codec":"ogg","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":20,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Apr 29 23:04:08 beolab-8000 volumio[6777]: info: Sending Spotify command to local API: /player/pause Apr 29 23:04:08 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:08+02:00" level=debug msg="pause track at 67814ms" Apr 29 23:04:08 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 29 23:04:08 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 29 23:04:08 beolab-8000 volumio[6777]: info: Discovery: Getting this device information Apr 29 23:04:08 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioGetState Apr 29 23:04:08 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 29 23:04:08 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:08+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:08 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:08+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:08 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:08+02:00" level=trace msg="emitting websocket event: paused" Apr 29 23:04:08 beolab-8000 volumio[6777]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","play_origin":"free-tier-artist"}} Apr 29 23:04:08 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:04:08 beolab-8000 volumio[6777]: SPOTIFY: {"status":"pause","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:04:08 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:04:08 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:04:08 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 23:04:08 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:04:08 beolab-8000 volumio[6777]: info: [ASDebug] CurState: pause PrevState: play Apr 29 23:04:08 beolab-8000 volumio[6777]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 29 23:04:08 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:04:08 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:04:08 beolab-8000 volumio[6777]: SPOTIFY: {"status":"pause","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:04:08 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:04:08 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:04:08 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:04:08 beolab-8000 volumio[6777]: info: [ASDebug] CurState: pause PrevState: play Apr 29 23:04:08 beolab-8000 volumio[6777]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 29 23:04:08 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:04:09 beolab-8000 volumio[6777]: info: [ASDebug] Togle GPIO: OFF Apr 29 23:04:09 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioVolatilePlay Apr 29 23:04:09 beolab-8000 volumio[6777]: info: CoreStateMachine::volatilePlay Apr 29 23:04:09 beolab-8000 volumio[6777]: info: Spotify Play Apr 29 23:04:09 beolab-8000 volumio[6777]: info: Sending Spotify command to local API: /player/resume Apr 29 23:04:09 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:09+02:00" level=trace msg="seek to 67814ms (diff: 152ms, samples: 2990597, bytes: 2299919)" uri="spotify:track:0kRMltaFIzcXkSbWZVBgMM" Apr 29 23:04:09 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:09+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Apr 29 23:04:09 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:09+02:00" level=debug msg="resume track at 67462ms" Apr 29 23:04:09 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:09+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:09 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:09+02:00" level=trace msg="scheduling prefetch in 102s" Apr 29 23:04:09 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:09+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:09 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:09+02:00" level=trace msg="emitting websocket event: playing" Apr 29 23:04:09 beolab-8000 volumio[6777]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","play_origin":"free-tier-artist"}} Apr 29 23:04:09 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:04:09 beolab-8000 volumio[6777]: SPOTIFY: {"status":"play","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:04:09 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:04:09 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:04:09 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 23:04:09 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:04:09 beolab-8000 volumio[6777]: info: [ASDebug] CurState: play PrevState: pause Apr 29 23:04:09 beolab-8000 volumio[6777]: info: [ASDebug] Togle GPIO: ON Apr 29 23:04:09 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:04:09 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioGetState Apr 29 23:04:09 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPause Apr 29 23:04:09 beolab-8000 volumio[6777]: info: CoreStateMachine::pause Apr 29 23:04:09 beolab-8000 volumio[6777]: info: CoreStateMachine::stPlaybackTimer Apr 29 23:04:09 beolab-8000 volumio[6777]: info: CoreStateMachine::servicePause Apr 29 23:04:09 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePause Apr 29 23:04:09 beolab-8000 volumio[6777]: info: Spotify Received pause Apr 29 23:04:09 beolab-8000 volumio[6777]: SPOTIFY: SPOTIFY PAUSE Apr 29 23:04:09 beolab-8000 volumio[6777]: SPOTIFY: {"status":"play","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","codec":"ogg","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":20,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Apr 29 23:04:09 beolab-8000 volumio[6777]: info: Sending Spotify command to local API: /player/pause Apr 29 23:04:09 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:09+02:00" level=debug msg="pause track at 67822ms" Apr 29 23:04:09 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:09+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:10 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:10+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:10 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:10+02:00" level=trace msg="emitting websocket event: paused" Apr 29 23:04:10 beolab-8000 volumio[6777]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","play_origin":"free-tier-artist"}} Apr 29 23:04:10 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:04:10 beolab-8000 volumio[6777]: SPOTIFY: {"status":"pause","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:04:10 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:04:10 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:04:10 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 23:04:10 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:04:10 beolab-8000 volumio[6777]: info: [ASDebug] CurState: pause PrevState: play Apr 29 23:04:10 beolab-8000 volumio[6777]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 29 23:04:10 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:04:10 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:04:10 beolab-8000 volumio[6777]: SPOTIFY: {"status":"pause","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:04:10 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:04:10 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:04:10 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:04:10 beolab-8000 volumio[6777]: info: [ASDebug] CurState: pause PrevState: play Apr 29 23:04:10 beolab-8000 volumio[6777]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 29 23:04:10 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:04:10 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioVolatilePlay Apr 29 23:04:10 beolab-8000 volumio[6777]: info: CoreStateMachine::volatilePlay Apr 29 23:04:10 beolab-8000 volumio[6777]: info: Spotify Play Apr 29 23:04:10 beolab-8000 volumio[6777]: info: Sending Spotify command to local API: /player/resume Apr 29 23:04:10 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:10+02:00" level=trace msg="seek to 67822ms (diff: 160ms, samples: 2990950, bytes: 2300204)" uri="spotify:track:0kRMltaFIzcXkSbWZVBgMM" Apr 29 23:04:10 beolab-8000 volumio[6777]: info: [ASDebug] Togle GPIO: OFF Apr 29 23:04:10 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:10+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Apr 29 23:04:10 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:10+02:00" level=debug msg="resume track at 67662ms" Apr 29 23:04:10 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:10+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:10 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:10+02:00" level=trace msg="scheduling prefetch in 102s" Apr 29 23:04:11 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:11+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:11 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:11+02:00" level=trace msg="emitting websocket event: playing" Apr 29 23:04:11 beolab-8000 volumio[6777]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","play_origin":"free-tier-artist"}} Apr 29 23:04:11 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:04:11 beolab-8000 volumio[6777]: SPOTIFY: {"status":"play","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:04:11 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:04:11 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:04:11 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 23:04:11 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:04:11 beolab-8000 volumio[6777]: info: [ASDebug] CurState: play PrevState: pause Apr 29 23:04:11 beolab-8000 volumio[6777]: info: [ASDebug] Togle GPIO: ON Apr 29 23:04:11 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:04:11 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioGetState Apr 29 23:04:11 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPause Apr 29 23:04:11 beolab-8000 volumio[6777]: info: CoreStateMachine::pause Apr 29 23:04:11 beolab-8000 volumio[6777]: info: CoreStateMachine::stPlaybackTimer Apr 29 23:04:11 beolab-8000 volumio[6777]: info: CoreStateMachine::servicePause Apr 29 23:04:11 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePause Apr 29 23:04:11 beolab-8000 volumio[6777]: info: Spotify Received pause Apr 29 23:04:11 beolab-8000 volumio[6777]: SPOTIFY: SPOTIFY PAUSE Apr 29 23:04:11 beolab-8000 volumio[6777]: SPOTIFY: {"status":"play","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","codec":"ogg","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":20,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Apr 29 23:04:11 beolab-8000 volumio[6777]: info: Sending Spotify command to local API: /player/pause Apr 29 23:04:11 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:11+02:00" level=debug msg="pause track at 67822ms" Apr 29 23:04:11 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:11+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:11 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:11+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:11 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:11+02:00" level=trace msg="emitting websocket event: paused" Apr 29 23:04:11 beolab-8000 volumio[6777]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","play_origin":"free-tier-artist"}} Apr 29 23:04:11 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:04:11 beolab-8000 volumio[6777]: SPOTIFY: {"status":"pause","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:04:11 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:04:11 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:04:11 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 23:04:11 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:04:11 beolab-8000 volumio[6777]: info: [ASDebug] CurState: pause PrevState: play Apr 29 23:04:11 beolab-8000 volumio[6777]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 29 23:04:11 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:04:11 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:04:11 beolab-8000 volumio[6777]: SPOTIFY: {"status":"pause","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:04:11 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:04:11 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:04:11 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:04:11 beolab-8000 volumio[6777]: info: [ASDebug] CurState: pause PrevState: play Apr 29 23:04:11 beolab-8000 volumio[6777]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 29 23:04:11 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:04:12 beolab-8000 volumio[6777]: info: [ASDebug] Togle GPIO: OFF Apr 29 23:04:12 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioVolatilePlay Apr 29 23:04:12 beolab-8000 volumio[6777]: info: CoreStateMachine::volatilePlay Apr 29 23:04:12 beolab-8000 volumio[6777]: info: Spotify Play Apr 29 23:04:12 beolab-8000 volumio[6777]: info: Sending Spotify command to local API: /player/resume Apr 29 23:04:12 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:12+02:00" level=trace msg="seek to 67822ms (diff: 160ms, samples: 2990950, bytes: 2300204)" uri="spotify:track:0kRMltaFIzcXkSbWZVBgMM" Apr 29 23:04:12 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:12+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Apr 29 23:04:12 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:12+02:00" level=debug msg="resume track at 67662ms" Apr 29 23:04:13 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:13+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:13 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:13+02:00" level=trace msg="scheduling prefetch in 102s" Apr 29 23:04:13 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:13+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:13 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:13+02:00" level=trace msg="emitting websocket event: playing" Apr 29 23:04:13 beolab-8000 volumio[6777]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","play_origin":"free-tier-artist"}} Apr 29 23:04:13 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:04:13 beolab-8000 volumio[6777]: SPOTIFY: {"status":"play","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:04:13 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:04:13 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:04:13 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 23:04:13 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:04:13 beolab-8000 volumio[6777]: info: [ASDebug] CurState: play PrevState: pause Apr 29 23:04:13 beolab-8000 volumio[6777]: info: [ASDebug] Togle GPIO: ON Apr 29 23:04:13 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:04:13 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioGetState Apr 29 23:04:13 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPause Apr 29 23:04:13 beolab-8000 volumio[6777]: info: CoreStateMachine::pause Apr 29 23:04:13 beolab-8000 volumio[6777]: info: CoreStateMachine::stPlaybackTimer Apr 29 23:04:13 beolab-8000 volumio[6777]: info: CoreStateMachine::servicePause Apr 29 23:04:13 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePause Apr 29 23:04:13 beolab-8000 volumio[6777]: info: Spotify Received pause Apr 29 23:04:13 beolab-8000 volumio[6777]: SPOTIFY: SPOTIFY PAUSE Apr 29 23:04:13 beolab-8000 volumio[6777]: SPOTIFY: {"status":"play","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","codec":"ogg","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":20,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Apr 29 23:04:13 beolab-8000 volumio[6777]: info: Sending Spotify command to local API: /player/pause Apr 29 23:04:13 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:13+02:00" level=debug msg="pause track at 67823ms" Apr 29 23:04:13 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:13+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:13 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:13+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:13 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:13+02:00" level=trace msg="emitting websocket event: paused" Apr 29 23:04:13 beolab-8000 volumio[6777]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","play_origin":"free-tier-artist"}} Apr 29 23:04:13 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:04:13 beolab-8000 volumio[6777]: SPOTIFY: {"status":"pause","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:04:13 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:04:13 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:04:13 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 23:04:13 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:04:13 beolab-8000 volumio[6777]: info: [ASDebug] CurState: pause PrevState: play Apr 29 23:04:13 beolab-8000 volumio[6777]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 29 23:04:13 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:04:13 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:04:13 beolab-8000 volumio[6777]: SPOTIFY: {"status":"pause","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:04:13 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:04:13 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:04:13 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:04:13 beolab-8000 volumio[6777]: info: [ASDebug] CurState: pause PrevState: play Apr 29 23:04:13 beolab-8000 volumio[6777]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 29 23:04:13 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:04:14 beolab-8000 volumio[6777]: info: [ASDebug] Togle GPIO: OFF Apr 29 23:04:14 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioVolatilePlay Apr 29 23:04:14 beolab-8000 volumio[6777]: info: CoreStateMachine::volatilePlay Apr 29 23:04:14 beolab-8000 volumio[6777]: info: Spotify Play Apr 29 23:04:14 beolab-8000 volumio[6777]: info: Sending Spotify command to local API: /player/resume Apr 29 23:04:14 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:14+02:00" level=trace msg="seek to 67823ms (diff: 161ms, samples: 2990994, bytes: 2300239)" uri="spotify:track:0kRMltaFIzcXkSbWZVBgMM" Apr 29 23:04:14 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:14+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Apr 29 23:04:14 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:14+02:00" level=debug msg="resume track at 67503ms" Apr 29 23:04:14 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:14+02:00" level=debug msg="put state request failed with status 429: Too many requests for user" Apr 29 23:04:15 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:15+02:00" level=trace msg="received accesspoint ping" Apr 29 23:04:15 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:15+02:00" level=trace msg="received accesspoint pong ack" Apr 29 23:04:15 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:15+02:00" level=trace msg="sent dealer ping" Apr 29 23:04:15 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:15+02:00" level=trace msg="received dealer pong" Apr 29 23:04:16 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:16+02:00" level=debug msg="put state request failed with status 429: Too many requests for user" Apr 29 23:04:17 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:17+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:17 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:17+02:00" level=trace msg="scheduling prefetch in 100s" Apr 29 23:04:17 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:17+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:17 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:17+02:00" level=trace msg="emitting websocket event: playing" Apr 29 23:04:17 beolab-8000 volumio[6777]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","play_origin":"free-tier-artist"}} Apr 29 23:04:17 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:04:17 beolab-8000 volumio[6777]: SPOTIFY: {"status":"play","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:04:17 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:04:17 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:04:17 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 23:04:17 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:04:17 beolab-8000 volumio[6777]: info: [ASDebug] CurState: play PrevState: pause Apr 29 23:04:17 beolab-8000 volumio[6777]: info: [ASDebug] Togle GPIO: ON Apr 29 23:04:17 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:04:17 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioGetState Apr 29 23:04:17 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPause Apr 29 23:04:17 beolab-8000 volumio[6777]: info: CoreStateMachine::pause Apr 29 23:04:17 beolab-8000 volumio[6777]: info: CoreStateMachine::stPlaybackTimer Apr 29 23:04:17 beolab-8000 volumio[6777]: info: CoreStateMachine::servicePause Apr 29 23:04:17 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePause Apr 29 23:04:17 beolab-8000 volumio[6777]: info: Spotify Received pause Apr 29 23:04:17 beolab-8000 volumio[6777]: SPOTIFY: SPOTIFY PAUSE Apr 29 23:04:17 beolab-8000 volumio[6777]: SPOTIFY: {"status":"play","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","codec":"ogg","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":20,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Apr 29 23:04:17 beolab-8000 volumio[6777]: info: Sending Spotify command to local API: /player/pause Apr 29 23:04:17 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:17+02:00" level=debug msg="pause track at 69888ms" Apr 29 23:04:17 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:17+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:17 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:17+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:17 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:17+02:00" level=trace msg="emitting websocket event: paused" Apr 29 23:04:17 beolab-8000 volumio[6777]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","play_origin":"free-tier-artist"}} Apr 29 23:04:17 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:04:17 beolab-8000 volumio[6777]: SPOTIFY: {"status":"pause","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:04:17 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:04:17 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:04:17 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 23:04:17 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:04:17 beolab-8000 volumio[6777]: info: [ASDebug] CurState: pause PrevState: play Apr 29 23:04:17 beolab-8000 volumio[6777]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 29 23:04:17 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:04:17 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:04:17 beolab-8000 volumio[6777]: SPOTIFY: {"status":"pause","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:04:17 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:04:17 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:04:17 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:04:17 beolab-8000 volumio[6777]: info: [ASDebug] CurState: pause PrevState: play Apr 29 23:04:17 beolab-8000 volumio[6777]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 29 23:04:17 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:04:18 beolab-8000 volumio[6777]: info: [ASDebug] Togle GPIO: OFF Apr 29 23:04:18 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioVolatilePlay Apr 29 23:04:18 beolab-8000 volumio[6777]: info: CoreStateMachine::volatilePlay Apr 29 23:04:18 beolab-8000 volumio[6777]: info: Spotify Play Apr 29 23:04:18 beolab-8000 volumio[6777]: info: Sending Spotify command to local API: /player/resume Apr 29 23:04:18 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:18+02:00" level=trace msg="seek to 69888ms (diff: 205ms, samples: 3082060, bytes: 2373440)" uri="spotify:track:0kRMltaFIzcXkSbWZVBgMM" Apr 29 23:04:18 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:18+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Apr 29 23:04:18 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:18+02:00" level=debug msg="resume track at 69683ms" Apr 29 23:04:18 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:18+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:18 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:18+02:00" level=trace msg="scheduling prefetch in 100s" Apr 29 23:04:18 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:18+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:18 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:18+02:00" level=trace msg="emitting websocket event: playing" Apr 29 23:04:18 beolab-8000 volumio[6777]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","play_origin":"free-tier-artist"}} Apr 29 23:04:18 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:04:18 beolab-8000 volumio[6777]: SPOTIFY: {"status":"play","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:04:18 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:04:18 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:04:18 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 23:04:18 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:04:18 beolab-8000 volumio[6777]: info: [ASDebug] CurState: play PrevState: pause Apr 29 23:04:18 beolab-8000 volumio[6777]: info: [ASDebug] Togle GPIO: ON Apr 29 23:04:18 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:04:18 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioGetState Apr 29 23:04:18 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPause Apr 29 23:04:18 beolab-8000 volumio[6777]: info: CoreStateMachine::pause Apr 29 23:04:18 beolab-8000 volumio[6777]: info: CoreStateMachine::stPlaybackTimer Apr 29 23:04:18 beolab-8000 volumio[6777]: info: CoreStateMachine::servicePause Apr 29 23:04:18 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePause Apr 29 23:04:18 beolab-8000 volumio[6777]: info: Spotify Received pause Apr 29 23:04:18 beolab-8000 volumio[6777]: SPOTIFY: SPOTIFY PAUSE Apr 29 23:04:18 beolab-8000 volumio[6777]: SPOTIFY: {"status":"play","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","codec":"ogg","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":20,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Apr 29 23:04:18 beolab-8000 volumio[6777]: info: Sending Spotify command to local API: /player/pause Apr 29 23:04:18 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:18+02:00" level=debug msg="pause track at 69874ms" Apr 29 23:04:19 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:19+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:19 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:19+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:19 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:19+02:00" level=trace msg="emitting websocket event: paused" Apr 29 23:04:19 beolab-8000 volumio[6777]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","play_origin":"free-tier-artist"}} Apr 29 23:04:19 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:04:19 beolab-8000 volumio[6777]: SPOTIFY: {"status":"pause","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:04:19 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:04:19 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:04:19 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 23:04:19 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:04:19 beolab-8000 volumio[6777]: info: [ASDebug] CurState: pause PrevState: play Apr 29 23:04:19 beolab-8000 volumio[6777]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 29 23:04:19 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:04:19 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:04:19 beolab-8000 volumio[6777]: SPOTIFY: {"status":"pause","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:04:19 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:04:19 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:04:19 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:04:19 beolab-8000 volumio[6777]: info: [ASDebug] CurState: pause PrevState: play Apr 29 23:04:19 beolab-8000 volumio[6777]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 29 23:04:19 beolab-8000 volumio[6777]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:04:19 beolab-8000 volumio[6777]: info: [ASDebug] Togle GPIO: OFF Apr 29 23:04:20 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 29 23:04:20 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 29 23:04:24 beolab-8000 volumio[6777]: info: GPIO-Buttons: Destroying trigger 0 Apr 29 23:04:24 beolab-8000 volumio[6777]: info: GPIO-Buttons stopped Apr 29 23:04:24 beolab-8000 volumio[6777]: info: Disabling plugin gpio-buttons Apr 29 23:04:24 beolab-8000 volumio[6777]: info: Done. Apr 29 23:04:26 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioVolatilePlay Apr 29 23:04:26 beolab-8000 volumio[6777]: info: CoreStateMachine::volatilePlay Apr 29 23:04:26 beolab-8000 volumio[6777]: info: Spotify Play Apr 29 23:04:26 beolab-8000 volumio[6777]: info: Sending Spotify command to local API: /player/resume Apr 29 23:04:26 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:26+02:00" level=trace msg="seek to 69874ms (diff: 191ms, samples: 3081443, bytes: 2372944)" uri="spotify:track:0kRMltaFIzcXkSbWZVBgMM" Apr 29 23:04:26 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:26+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Apr 29 23:04:26 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:26+02:00" level=debug msg="resume track at 69413ms" Apr 29 23:04:26 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:26+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:26 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:26+02:00" level=trace msg="scheduling prefetch in 100s" Apr 29 23:04:26 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:26+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:04:26 beolab-8000 go-librespot[6978]: time="2025-04-29T23:04:26+02:00" level=trace msg="emitting websocket event: playing" Apr 29 23:04:26 beolab-8000 volumio[6777]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","play_origin":"free-tier-artist"}} Apr 29 23:04:26 beolab-8000 volumio[6777]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:04:26 beolab-8000 volumio[6777]: SPOTIFY: {"status":"play","service":"spop","title":"miro","artist":"berlioz, Ted Jasper","album":"miro","albumart":"https://i.scdn.co/image/ab67616d00001e023ee31764a312a1744f418e9f","uri":"spotify:track:0kRMltaFIzcXkSbWZVBgMM","trackType":"spotify","seek":68000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:04:26 beolab-8000 volumio[6777]: info: CoreCommandRouter::servicePushState Apr 29 23:04:26 beolab-8000 volumio[6777]: info: CoreStateMachine::pushState Apr 29 23:04:26 beolab-8000 volumio[6777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 23:04:26 beolab-8000 volumio[6777]: info: CoreCommandRouter::volumioPushState Apr 29 23:04:26 beolab-8000 volumio[6777]: info: [ASDebug] CurState: play PrevState: pause Apr 29 23:04:26 beolab-8000 volumio[6777]: info: [ASDebug] Togle GPIO: ON Apr 29 23:04:26 beolab-8000 volumio[6777]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 29 23:04:26 beolab-8000 volumio[6777]: Error: ENODEV: no such device, write Apr 29 23:04:26 beolab-8000 volumio[6777]: at Object.writeSync (fs.js:711:3) Apr 29 23:04:26 beolab-8000 volumio[6777]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Apr 29 23:04:26 beolab-8000 volumio[6777]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:207:23) Apr 29 23:04:26 beolab-8000 volumio[6777]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:190:86) Apr 29 23:04:26 beolab-8000 volumio[6777]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Apr 29 23:04:26 beolab-8000 volumio[6777]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10) Apr 29 23:04:26 beolab-8000 volumio[6777]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12) Apr 29 23:04:26 beolab-8000 volumio[6777]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Apr 29 23:04:26 beolab-8000 volumio[6777]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Apr 29 23:04:26 beolab-8000 volumio[6777]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8) Apr 29 23:04:26 beolab-8000 volumio[6777]: at Decoder. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Apr 29 23:04:26 beolab-8000 volumio[6777]: at Decoder.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Apr 29 23:04:26 beolab-8000 volumio[6777]: at Decoder.add (/data/plugins/system_controller/ampswitch/node_modules/socket.io-parser/index.js:254:12) Apr 29 23:04:26 beolab-8000 volumio[6777]: at Manager.ondata (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:339:16) Apr 29 23:04:26 beolab-8000 volumio[6777]: at Socket. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Apr 29 23:04:26 beolab-8000 volumio[6777]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) { Apr 29 23:04:26 beolab-8000 volumio[6777]: errno: -19, Apr 29 23:04:26 beolab-8000 volumio[6777]: syscall: 'write', Apr 29 23:04:26 beolab-8000 volumio[6777]: code: 'ENODEV' Apr 29 23:04:26 beolab-8000 volumio[6777]: } Apr 29 23:04:26 beolab-8000 volumio[6777]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 29 23:04:27 beolab-8000 sudo[7557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-29 23:03 Apr 29 23:04:27 beolab-8000 sudo[7557]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"