-- Logs begin at Thu 2019-02-14 12:11:58 IST, end at Fri 2025-05-23 17:41:02 IDT. -- May 23 17:40:00 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 23 17:40:04 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 23 17:40:07 volumio go-librespot[1478]: time="2025-05-23T17:40:07+03:00" level=debug msg="handling pause player command from a541cef2d6112e4e859a49225adeedf3426b5dff" May 23 17:40:07 volumio go-librespot[1478]: time="2025-05-23T17:40:07+03:00" level=debug msg="pause track at 9608ms" May 23 17:40:07 volumio go-librespot[1478]: time="2025-05-23T17:40:07+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 23 17:40:07 volumio go-librespot[1478]: time="2025-05-23T17:40:07+03:00" level=debug msg="sending successful reply for dealer request" May 23 17:40:07 volumio go-librespot[1478]: time="2025-05-23T17:40:07+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 23 17:40:07 volumio go-librespot[1478]: time="2025-05-23T17:40:07+03:00" level=trace msg="emitting websocket event: paused" May 23 17:40:07 volumio volumio[1316]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:4NBi5vc4d6xz9qi6rArART","play_origin":"playlist"}} May 23 17:40:07 volumio volumio[1316]: info: Spotify is playing in volatile mode May 23 17:40:07 volumio volumio[1316]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 17:40:07 volumio volumio[1316]: SPOTIFY: UNSET VOLATILE May 23 17:40:07 volumio volumio[1316]: SPOTIFY: {"status":"play","position":0,"title":"Depeche Mode - Peace","artist":"80s80s Depeche Mode","album":"","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music19/v4/75/6a/cb/756acb13-c950-9a99-e2fb-acc19ebb0ce7/source/600x600bb.jpg","uri":"http://streams.80s80s.de/dm/mp3-192/volumio","trackType":"80s80s Radio","seek":186481,"duration":"254","samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":true,"mute":false,"stream":"80s80s Radio","volatile":false,"service":"80s80s"} May 23 17:40:07 volumio volumio[1316]: info: Setting Spotify stop after unset volatile call May 23 17:40:07 volumio volumio[1316]: SPOTIFY: PUSH STATE SPOTIFY May 23 17:40:07 volumio volumio[1316]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 23 17:40:07 volumio volumio[1316]: info: CoreCommandRouter::servicePushState May 23 17:40:07 volumio volumio[1316]: info: CoreStateMachine::pushState May 23 17:40:07 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:40:07 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState May 23 17:40:07 volumio volumio[1316]: info: Spotify Stop May 23 17:40:07 volumio volumio[1316]: SPOTIFY: SPOTIFY STOP May 23 17:40:07 volumio volumio[1316]: SPOTIFY: {"status":"pause","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","codec":"ogg","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":true,"stream":false,"volatile":true,"service":"spop"} May 23 17:40:08 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 23 17:40:09 volumio go-librespot[1478]: time="2025-05-23T17:40:09+03:00" level=debug msg="handling resume player command from a541cef2d6112e4e859a49225adeedf3426b5dff" May 23 17:40:09 volumio go-librespot[1478]: time="2025-05-23T17:40:09+03:00" level=trace msg="seek to 9608ms (diff: 110ms, samples: 423712, bytes: 355229)" uri="spotify:track:4NBi5vc4d6xz9qi6rArART" May 23 17:40:09 volumio go-librespot[1478]: time="2025-05-23T17:40:09+03:00" level=warning msg="failed handling dealer request" error="failed starting playback: ALSA error at snd_pcm_open: Device or resource busy" May 23 17:40:11 volumio go-librespot[1478]: time="2025-05-23T17:40:11+03:00" level=debug msg="update volume to 65535/65535" May 23 17:40:12 volumio go-librespot[1478]: time="2025-05-23T17:40:12+03:00" level=debug msg="put connect state because VOLUME_CHANGED" May 23 17:40:12 volumio go-librespot[1478]: time="2025-05-23T17:40:12+03:00" level=trace msg="emitting websocket event: volume" May 23 17:40:12 volumio volumio[1316]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} May 23 17:40:12 volumio volumio[1316]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 May 23 17:40:12 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 23 17:40:14 volumio go-librespot[1478]: time="2025-05-23T17:40:14+03:00" level=debug msg="update volume to 64880/65535" May 23 17:40:14 volumio go-librespot[1478]: time="2025-05-23T17:40:14+03:00" level=debug msg="update volume to 64224/65535" May 23 17:40:14 volumio go-librespot[1478]: time="2025-05-23T17:40:14+03:00" level=debug msg="update volume to 62258/65535" May 23 17:40:15 volumio go-librespot[1478]: time="2025-05-23T17:40:15+03:00" level=debug msg="put connect state because VOLUME_CHANGED" May 23 17:40:15 volumio go-librespot[1478]: time="2025-05-23T17:40:15+03:00" level=trace msg="emitting websocket event: volume" May 23 17:40:15 volumio volumio[1316]: SPOTIFY: received: {"type":"volume","data":{"value":95,"max":100}} May 23 17:40:15 volumio volumio[1316]: SPOTIFY: RECEIVED SPOTIFY VOLUME 95 May 23 17:40:15 volumio volumio[1316]: info: Setting Volumio Volume from Spotify: 95 May 23 17:40:15 volumio volumio[1316]: info: VolumeController::SetAlsaVolume95 May 23 17:40:15 volumio volumio[1316]: info: CoreStateMachine::pushState May 23 17:40:15 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState May 23 17:40:15 volumio go-librespot[1478]: time="2025-05-23T17:40:15+03:00" level=debug msg="update volume to 62914/65535" May 23 17:40:16 volumio go-librespot[1478]: time="2025-05-23T17:40:16+03:00" level=debug msg="update volume to 63569/65535" May 23 17:40:16 volumio go-librespot[1478]: time="2025-05-23T17:40:16+03:00" level=debug msg="update volume to 64880/65535" May 23 17:40:17 volumio go-librespot[1478]: time="2025-05-23T17:40:17+03:00" level=debug msg="update volume to 64224/65535" May 23 17:40:17 volumio go-librespot[1478]: time="2025-05-23T17:40:17+03:00" level=debug msg="update volume to 61603/65535" May 23 17:40:17 volumio go-librespot[1478]: time="2025-05-23T17:40:17+03:00" level=debug msg="update volume to 56360/65535" May 23 17:40:18 volumio go-librespot[1478]: time="2025-05-23T17:40:18+03:00" level=debug msg="update volume to 51117/65535" May 23 17:40:18 volumio go-librespot[1478]: time="2025-05-23T17:40:18+03:00" level=debug msg="update volume to 45874/65535" May 23 17:40:18 volumio go-librespot[1478]: time="2025-05-23T17:40:18+03:00" level=debug msg="update volume to 41942/65535" May 23 17:40:20 volumio go-librespot[1478]: time="2025-05-23T17:40:20+03:00" level=debug msg="put connect state because VOLUME_CHANGED" May 23 17:40:20 volumio go-librespot[1478]: time="2025-05-23T17:40:20+03:00" level=trace msg="emitting websocket event: volume" May 23 17:40:20 volumio volumio[1316]: SPOTIFY: received: {"type":"volume","data":{"value":64,"max":100}} May 23 17:40:20 volumio volumio[1316]: SPOTIFY: RECEIVED SPOTIFY VOLUME 64 May 23 17:40:20 volumio volumio[1316]: info: Setting Volumio Volume from Spotify: 64 May 23 17:40:20 volumio volumio[1316]: info: VolumeController::SetAlsaVolume64 May 23 17:40:20 volumio volumio[1316]: info: CoreStateMachine::pushState May 23 17:40:20 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState May 23 17:40:23 volumio go-librespot[1478]: time="2025-05-23T17:40:23+03:00" level=trace msg="sent dealer ping" May 23 17:40:23 volumio go-librespot[1478]: time="2025-05-23T17:40:23+03:00" level=trace msg="received dealer pong" May 23 17:40:28 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 17:40:28 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 17:40:28 volumio volumio[1316]: info: Discovery: Getting this device information May 23 17:40:28 volumio volumio[1316]: info: CoreCommandRouter::volumioGetState May 23 17:40:28 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 17:40:28 volumio volumio[1316]: verbose: New Socket.io Connection to 192.168.1.122:3000 from 192.168.1.119 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 May 23 17:40:28 volumio volumio[1316]: info: CoreCommandRouter::volumioGetState May 23 17:40:28 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 23 17:40:28 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 23 17:40:29 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 17:40:29 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 17:40:29 volumio volumio[1316]: info: Discovery: Getting this device information May 23 17:40:29 volumio volumio[1316]: info: CoreCommandRouter::volumioGetState May 23 17:40:29 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 17:40:31 volumio volumio[1316]: info: CoreCommandRouter::volumioGetState May 23 17:40:31 volumio volumio[1316]: verbose: New Socket.io Connection to 192.168.1.122:3000 from 192.168.1.119 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 May 23 17:40:31 volumio volumio[1316]: info: CoreCommandRouter::volumioGetState May 23 17:40:31 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 23 17:40:31 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 23 17:40:31 volumio volumio[1316]: info: CoreCommandRouter::volumioGetState May 23 17:40:32 volumio sudo[3335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 23 17:40:32 volumio sudo[3335]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:40:32 volumio sudo[3335]: pam_unix(sudo:session): session closed for user root May 23 17:40:32 volumio sudo[3337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 23 17:40:32 volumio sudo[3337]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:40:32 volumio sudo[3337]: pam_unix(sudo:session): session closed for user root May 23 17:40:32 volumio volumio[1316]: verbose: New Socket.io Connection to 192.168.1.122 from 192.168.1.119 UA: Mozilla/5.0 (Linux; Android 14; SM-A556E Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 23 17:40:32 volumio sudo[3341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 23 17:40:32 volumio sudo[3341]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:40:32 volumio sudo[3341]: pam_unix(sudo:session): session closed for user root May 23 17:40:32 volumio sudo[3344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 23 17:40:32 volumio sudo[3344]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:40:32 volumio sudo[3344]: pam_unix(sudo:session): session closed for user root May 23 17:40:32 volumio volumio[1316]: verbose: New Socket.io Connection to 192.168.1.122 from 192.168.1.119 UA: Mozilla/5.0 (Linux; Android 14; SM-A556E Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 23 17:40:32 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 23 17:40:32 volumio volumio[1316]: info: CoreCommandRouter::volumioGetVisibleSources May 23 17:40:32 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 23 17:40:32 volumio volumio[1316]: info: CoreCommandRouter::volumioGetState May 23 17:40:32 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 23 17:40:32 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 23 17:40:32 volumio volumio[1316]: info: Received Get System Info May 23 17:40:32 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 17:40:32 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 17:40:32 volumio volumio[1316]: info: Discovery: Getting this device information May 23 17:40:32 volumio volumio[1316]: info: CoreCommandRouter::volumioGetState May 23 17:40:32 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 17:40:32 volumio volumio[1316]: info: CoreCommandRouter::volumioGetState May 23 17:40:32 volumio volumio[1316]: info: Listing playlists May 23 17:40:32 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 23 17:40:32 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 23 17:40:32 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 23 17:40:34 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 23 17:40:34 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 23 17:40:34 volumio volumio[1316]: info: Received Get System Info May 23 17:40:34 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 17:40:34 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 17:40:34 volumio volumio[1316]: info: Discovery: Getting this device information May 23 17:40:34 volumio volumio[1316]: info: CoreCommandRouter::volumioGetState May 23 17:40:34 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 17:40:35 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 23 17:40:35 volumio volumio[1316]: info: Received Get System Info May 23 17:40:35 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 17:40:35 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 17:40:35 volumio volumio[1316]: info: Discovery: Getting this device information May 23 17:40:35 volumio volumio[1316]: info: CoreCommandRouter::volumioGetState May 23 17:40:35 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 17:40:42 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 23 17:40:42 volumio volumio[1316]: info: CoreCommandRouter::volumioVolatilePlay May 23 17:40:42 volumio volumio[1316]: info: CoreStateMachine::volatilePlay May 23 17:40:42 volumio volumio[1316]: info: Spotify Play May 23 17:40:42 volumio volumio[1316]: info: Sending Spotify command to local API: /player/resume May 23 17:40:42 volumio go-librespot[1478]: time="2025-05-23T17:40:42+03:00" level=trace msg="seek to 9608ms (diff: 110ms, samples: 423712, bytes: 355229)" uri="spotify:track:4NBi5vc4d6xz9qi6rArART" May 23 17:40:43 volumio volumio[1316]: info: CoreCommandRouter::volumioVolatilePlay May 23 17:40:43 volumio volumio[1316]: info: CoreStateMachine::volatilePlay May 23 17:40:43 volumio volumio[1316]: info: Spotify Play May 23 17:40:43 volumio volumio[1316]: info: Sending Spotify command to local API: /player/resume May 23 17:40:43 volumio go-librespot[1478]: time="2025-05-23T17:40:43+03:00" level=trace msg="seek to 9608ms (diff: 110ms, samples: 423712, bytes: 355229)" uri="spotify:track:4NBi5vc4d6xz9qi6rArART" May 23 17:40:43 volumio volumio[1316]: info: CoreCommandRouter::volumioVolatilePlay May 23 17:40:43 volumio volumio[1316]: info: CoreStateMachine::volatilePlay May 23 17:40:43 volumio volumio[1316]: info: Spotify Play May 23 17:40:43 volumio volumio[1316]: info: Sending Spotify command to local API: /player/resume May 23 17:40:43 volumio go-librespot[1478]: time="2025-05-23T17:40:43+03:00" level=trace msg="seek to 9608ms (diff: 110ms, samples: 423712, bytes: 355229)" uri="spotify:track:4NBi5vc4d6xz9qi6rArART" May 23 17:40:43 volumio volumio[1316]: info: CoreCommandRouter::volumioVolatilePlay May 23 17:40:43 volumio volumio[1316]: info: CoreStateMachine::volatilePlay May 23 17:40:43 volumio volumio[1316]: info: Spotify Play May 23 17:40:43 volumio volumio[1316]: info: Sending Spotify command to local API: /player/resume May 23 17:40:43 volumio go-librespot[1478]: time="2025-05-23T17:40:43+03:00" level=trace msg="seek to 9608ms (diff: 110ms, samples: 423712, bytes: 355229)" uri="spotify:track:4NBi5vc4d6xz9qi6rArART" May 23 17:40:44 volumio volumio[1316]: info: CoreCommandRouter::volumioVolatilePlay May 23 17:40:44 volumio volumio[1316]: info: CoreStateMachine::volatilePlay May 23 17:40:44 volumio volumio[1316]: info: Spotify Play May 23 17:40:44 volumio volumio[1316]: info: Sending Spotify command to local API: /player/resume May 23 17:40:44 volumio go-librespot[1478]: time="2025-05-23T17:40:44+03:00" level=trace msg="seek to 9608ms (diff: 110ms, samples: 423712, bytes: 355229)" uri="spotify:track:4NBi5vc4d6xz9qi6rArART" May 23 17:40:44 volumio volumio[1316]: info: CoreCommandRouter::volumioVolatilePlay May 23 17:40:44 volumio volumio[1316]: info: CoreStateMachine::volatilePlay May 23 17:40:44 volumio volumio[1316]: info: Spotify Play May 23 17:40:44 volumio volumio[1316]: info: Sending Spotify command to local API: /player/resume May 23 17:40:44 volumio go-librespot[1478]: time="2025-05-23T17:40:44+03:00" level=trace msg="seek to 9608ms (diff: 110ms, samples: 423712, bytes: 355229)" uri="spotify:track:4NBi5vc4d6xz9qi6rArART" May 23 17:40:44 volumio volumio[1316]: info: CoreCommandRouter::volumioVolatilePlay May 23 17:40:44 volumio volumio[1316]: info: CoreStateMachine::volatilePlay May 23 17:40:44 volumio volumio[1316]: info: Spotify Play May 23 17:40:44 volumio volumio[1316]: info: Sending Spotify command to local API: /player/resume May 23 17:40:44 volumio go-librespot[1478]: time="2025-05-23T17:40:44+03:00" level=trace msg="seek to 9608ms (diff: 110ms, samples: 423712, bytes: 355229)" uri="spotify:track:4NBi5vc4d6xz9qi6rArART" May 23 17:40:44 volumio volumio[1316]: info: CoreCommandRouter::volumioVolatilePlay May 23 17:40:44 volumio volumio[1316]: info: CoreStateMachine::volatilePlay May 23 17:40:44 volumio volumio[1316]: info: Spotify Play May 23 17:40:44 volumio volumio[1316]: info: Sending Spotify command to local API: /player/resume May 23 17:40:44 volumio go-librespot[1478]: time="2025-05-23T17:40:44+03:00" level=trace msg="seek to 9608ms (diff: 110ms, samples: 423712, bytes: 355229)" uri="spotify:track:4NBi5vc4d6xz9qi6rArART" May 23 17:40:45 volumio volumio[1316]: info: CoreCommandRouter::volumioVolatilePlay May 23 17:40:45 volumio volumio[1316]: info: CoreStateMachine::volatilePlay May 23 17:40:45 volumio volumio[1316]: info: Spotify Play May 23 17:40:45 volumio volumio[1316]: info: Sending Spotify command to local API: /player/resume May 23 17:40:45 volumio go-librespot[1478]: time="2025-05-23T17:40:45+03:00" level=trace msg="seek to 9608ms (diff: 110ms, samples: 423712, bytes: 355229)" uri="spotify:track:4NBi5vc4d6xz9qi6rArART" May 23 17:40:45 volumio volumio[1316]: info: CoreCommandRouter::volumioVolatilePlay May 23 17:40:45 volumio volumio[1316]: info: CoreStateMachine::volatilePlay May 23 17:40:45 volumio volumio[1316]: info: Spotify Play May 23 17:40:45 volumio volumio[1316]: info: Sending Spotify command to local API: /player/resume May 23 17:40:45 volumio go-librespot[1478]: time="2025-05-23T17:40:45+03:00" level=trace msg="seek to 9608ms (diff: 110ms, samples: 423712, bytes: 355229)" uri="spotify:track:4NBi5vc4d6xz9qi6rArART" May 23 17:40:45 volumio volumio[1316]: info: CoreCommandRouter::volumioVolatilePlay May 23 17:40:45 volumio volumio[1316]: info: CoreStateMachine::volatilePlay May 23 17:40:45 volumio volumio[1316]: info: Spotify Play May 23 17:40:45 volumio volumio[1316]: info: Sending Spotify command to local API: /player/resume May 23 17:40:45 volumio go-librespot[1478]: time="2025-05-23T17:40:45+03:00" level=trace msg="seek to 9608ms (diff: 110ms, samples: 423712, bytes: 355229)" uri="spotify:track:4NBi5vc4d6xz9qi6rArART" May 23 17:40:45 volumio volumio[1316]: info: CoreCommandRouter::volumioVolatilePlay May 23 17:40:45 volumio volumio[1316]: info: CoreStateMachine::volatilePlay May 23 17:40:45 volumio volumio[1316]: info: Spotify Play May 23 17:40:45 volumio volumio[1316]: info: Sending Spotify command to local API: /player/resume May 23 17:40:45 volumio go-librespot[1478]: time="2025-05-23T17:40:45+03:00" level=trace msg="seek to 9608ms (diff: 110ms, samples: 423712, bytes: 355229)" uri="spotify:track:4NBi5vc4d6xz9qi6rArART" May 23 17:40:51 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 23 17:40:51 volumio volumio[1316]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 23 17:40:51 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 23 17:40:51 volumio volumio[1316]: info: Received Get System Version May 23 17:40:51 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 23 17:40:51 volumio volumio[1316]: info: Received Get System Info May 23 17:40:51 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 17:40:51 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 17:40:51 volumio volumio[1316]: info: Discovery: Getting this device information May 23 17:40:51 volumio volumio[1316]: info: CoreCommandRouter::volumioGetState May 23 17:40:51 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 17:40:53 volumio volumio[1316]: info: CoreCommandRouter::volumioGetState May 23 17:40:53 volumio go-librespot[1478]: time="2025-05-23T17:40:53+03:00" level=trace msg="sent dealer ping" May 23 17:40:53 volumio go-librespot[1478]: time="2025-05-23T17:40:53+03:00" level=trace msg="received dealer pong" May 23 17:40:53 volumio sudo[3421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 23 17:40:53 volumio sudo[3421]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:40:53 volumio sudo[3421]: pam_unix(sudo:session): session closed for user root May 23 17:40:53 volumio sudo[3424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 23 17:40:53 volumio sudo[3424]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:40:53 volumio sudo[3424]: pam_unix(sudo:session): session closed for user root May 23 17:40:53 volumio volumio[1316]: verbose: New Socket.io Connection to 192.168.1.122 from 192.168.1.119 UA: Mozilla/5.0 (Linux; Android 14; SM-A556E Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 May 23 17:40:54 volumio sudo[3429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 23 17:40:54 volumio sudo[3429]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:40:54 volumio sudo[3429]: pam_unix(sudo:session): session closed for user root May 23 17:40:54 volumio sudo[3432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 23 17:40:54 volumio sudo[3432]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:40:54 volumio sudo[3432]: pam_unix(sudo:session): session closed for user root May 23 17:40:54 volumio volumio[1316]: verbose: New Socket.io Connection to 192.168.1.122 from 192.168.1.119 UA: Mozilla/5.0 (Linux; Android 14; SM-A556E Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 May 23 17:40:54 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 23 17:40:54 volumio volumio[1316]: info: CoreCommandRouter::volumioGetVisibleSources May 23 17:40:54 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 23 17:40:54 volumio volumio[1316]: info: CoreCommandRouter::volumioGetState May 23 17:40:54 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 23 17:40:54 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 23 17:40:54 volumio volumio[1316]: info: Received Get System Info May 23 17:40:54 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 17:40:54 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 17:40:54 volumio volumio[1316]: info: Discovery: Getting this device information May 23 17:40:54 volumio volumio[1316]: info: CoreCommandRouter::volumioGetState May 23 17:40:54 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 17:40:54 volumio volumio[1316]: info: CoreCommandRouter::volumioGetState May 23 17:40:54 volumio volumio[1316]: info: Listing playlists May 23 17:40:54 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 23 17:40:54 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 23 17:40:54 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 23 17:40:56 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 23 17:40:56 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 23 17:40:56 volumio volumio[1316]: info: Received Get System Info May 23 17:40:56 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 17:40:56 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 17:40:56 volumio volumio[1316]: info: Discovery: Getting this device information May 23 17:40:56 volumio volumio[1316]: info: CoreCommandRouter::volumioGetState May 23 17:40:56 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 17:40:57 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 23 17:40:57 volumio volumio[1316]: info: Received Get System Info May 23 17:40:57 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 17:40:57 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 17:40:57 volumio volumio[1316]: info: Discovery: Getting this device information May 23 17:40:57 volumio volumio[1316]: info: CoreCommandRouter::volumioGetState May 23 17:40:57 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 17:40:58 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 17:40:58 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 17:40:58 volumio volumio[1316]: info: Discovery: Getting this device information May 23 17:40:58 volumio volumio[1316]: info: CoreCommandRouter::volumioGetState May 23 17:40:58 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 17:41:02 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 23 17:41:02 volumio volumio[1316]: info: [1748011262431] [80s80s] Pushing the next song state Depeche Mode - Peace and getting next track. May 23 17:41:02 volumio volumio[1316]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 17:41:02 volumio volumio[1316]: TypeError: Cannot set property 'name' of undefined May 23 17:41:02 volumio volumio[1316]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) May 23 17:41:02 volumio volumio[1316]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) May 23 17:41:02 volumio volumio[1316]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) May 23 17:41:02 volumio volumio[1316]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) May 23 17:41:02 volumio volumio[1316]: at processImmediate (internal/timers.js:461:21) May 23 17:41:02 volumio volumio[1316]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 17:41:02 volumio sudo[3473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-23 17:40 May 23 17:41:02 volumio sudo[3473]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"