-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2025-05-08 13:01:13 CEST. -- May 08 13:00:02 volumio systemd[1]: tft24-display-startup.service: Service RestartSec=10s expired, scheduling restart. May 08 13:00:02 volumio systemd[1]: tft24-display-startup.service: Scheduled restart job, restart counter is at 27. May 08 13:00:02 volumio systemd[1]: Stopped Volumio Display Startup Message Daemon. May 08 13:00:02 volumio systemd[1]: Started Volumio Display Startup Message Daemon. May 08 13:00:02 volumio VolumioTftDisplay[1965]: Traceback (most recent call last): May 08 13:00:02 volumio VolumioTftDisplay[1965]: File "/data/plugins/user_interface/tft24-display/service/tft24-display-startup.py", line 9, in May 08 13:00:02 volumio VolumioTftDisplay[1965]: from PIL import ImageFont May 08 13:00:02 volumio VolumioTftDisplay[1965]: ImportError: No module named PIL May 08 13:00:02 volumio systemd[1]: tft24-display-startup.service: Main process exited, code=exited, status=1/FAILURE May 08 13:00:02 volumio systemd[1]: tft24-display-startup.service: Failed with result 'exit-code'. May 08 13:00:12 volumio systemd[1]: tft24-display-startup.service: Service RestartSec=10s expired, scheduling restart. May 08 13:00:12 volumio systemd[1]: tft24-display-startup.service: Scheduled restart job, restart counter is at 28. May 08 13:00:12 volumio systemd[1]: Stopped Volumio Display Startup Message Daemon. May 08 13:00:12 volumio systemd[1]: Started Volumio Display Startup Message Daemon. May 08 13:00:12 volumio VolumioTftDisplay[1980]: Traceback (most recent call last): May 08 13:00:12 volumio VolumioTftDisplay[1980]: File "/data/plugins/user_interface/tft24-display/service/tft24-display-startup.py", line 9, in May 08 13:00:12 volumio VolumioTftDisplay[1980]: from PIL import ImageFont May 08 13:00:12 volumio VolumioTftDisplay[1980]: ImportError: No module named PIL May 08 13:00:12 volumio systemd[1]: tft24-display-startup.service: Main process exited, code=exited, status=1/FAILURE May 08 13:00:12 volumio systemd[1]: tft24-display-startup.service: Failed with result 'exit-code'. May 08 13:00:20 volumio volumio[1018]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 13:00:22 volumio systemd[1]: tft24-display-startup.service: Service RestartSec=10s expired, scheduling restart. May 08 13:00:22 volumio systemd[1]: tft24-display-startup.service: Scheduled restart job, restart counter is at 29. May 08 13:00:22 volumio systemd[1]: Stopped Volumio Display Startup Message Daemon. May 08 13:00:22 volumio systemd[1]: Started Volumio Display Startup Message Daemon. May 08 13:00:22 volumio VolumioTftDisplay[1999]: Traceback (most recent call last): May 08 13:00:22 volumio VolumioTftDisplay[1999]: File "/data/plugins/user_interface/tft24-display/service/tft24-display-startup.py", line 9, in May 08 13:00:22 volumio VolumioTftDisplay[1999]: from PIL import ImageFont May 08 13:00:22 volumio VolumioTftDisplay[1999]: ImportError: No module named PIL May 08 13:00:22 volumio systemd[1]: tft24-display-startup.service: Main process exited, code=exited, status=1/FAILURE May 08 13:00:22 volumio systemd[1]: tft24-display-startup.service: Failed with result 'exit-code'. May 08 13:00:28 volumio go-librespot[1211]: time="2025-05-08T13:00:28+02:00" level=trace msg="sent dealer ping" May 08 13:00:28 volumio go-librespot[1211]: time="2025-05-08T13:00:28+02:00" level=trace msg="received dealer pong" May 08 13:00:33 volumio systemd[1]: tft24-display-startup.service: Service RestartSec=10s expired, scheduling restart. May 08 13:00:33 volumio systemd[1]: tft24-display-startup.service: Scheduled restart job, restart counter is at 30. May 08 13:00:33 volumio systemd[1]: Stopped Volumio Display Startup Message Daemon. May 08 13:00:33 volumio systemd[1]: Started Volumio Display Startup Message Daemon. May 08 13:00:33 volumio VolumioTftDisplay[2052]: Traceback (most recent call last): May 08 13:00:33 volumio VolumioTftDisplay[2052]: File "/data/plugins/user_interface/tft24-display/service/tft24-display-startup.py", line 9, in May 08 13:00:33 volumio VolumioTftDisplay[2052]: from PIL import ImageFont May 08 13:00:33 volumio VolumioTftDisplay[2052]: ImportError: No module named PIL May 08 13:00:33 volumio systemd[1]: tft24-display-startup.service: Main process exited, code=exited, status=1/FAILURE May 08 13:00:33 volumio systemd[1]: tft24-display-startup.service: Failed with result 'exit-code'. May 08 13:00:33 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 08 13:00:33 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 08 13:00:36 volumio volumio[1018]: info: Enabling plugin tft24-display May 08 13:00:36 volumio volumio[1018]: info: Loading plugin "tft24-display"... May 08 13:00:36 volumio volumio[1018]: error: !!!! WARNING !!!! May 08 13:00:36 volumio volumio[1018]: error: The plugin user_interface/tft24-display failed to load, setting it to stopped. Error: Error: Cannot find module 'v-conf' May 08 13:00:36 volumio volumio[1018]: Require stack: May 08 13:00:36 volumio volumio[1018]: - /data/plugins/user_interface/tft24-display/index.js May 08 13:00:36 volumio volumio[1018]: - /volumio/app/pluginmanager.js May 08 13:00:36 volumio volumio[1018]: - /volumio/app/index.js May 08 13:00:36 volumio volumio[1018]: - /volumio/index.js May 08 13:00:36 volumio volumio[1018]: error: Stack trace: Error: Cannot find module 'v-conf' May 08 13:00:36 volumio volumio[1018]: Require stack: May 08 13:00:36 volumio volumio[1018]: - /data/plugins/user_interface/tft24-display/index.js May 08 13:00:36 volumio volumio[1018]: - /volumio/app/pluginmanager.js May 08 13:00:36 volumio volumio[1018]: - /volumio/app/index.js May 08 13:00:36 volumio volumio[1018]: - /volumio/index.js May 08 13:00:36 volumio volumio[1018]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:880:15) May 08 13:00:36 volumio volumio[1018]: at Function.Module._load (internal/modules/cjs/loader.js:725:27) May 08 13:00:36 volumio volumio[1018]: at Module.require (internal/modules/cjs/loader.js:952:19) May 08 13:00:36 volumio volumio[1018]: at require (internal/modules/cjs/helpers.js:88:18) May 08 13:00:36 volumio volumio[1018]: at Object. (/data/plugins/user_interface/tft24-display/index.js:3:18) May 08 13:00:36 volumio volumio[1018]: at Module._compile (internal/modules/cjs/loader.js:1063:30) May 08 13:00:36 volumio volumio[1018]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) May 08 13:00:36 volumio volumio[1018]: at Module.load (internal/modules/cjs/loader.js:928:32) May 08 13:00:36 volumio volumio[1018]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) May 08 13:00:36 volumio volumio[1018]: at Module.require (internal/modules/cjs/loader.js:952:19) May 08 13:00:36 volumio volumio[1018]: at require (internal/modules/cjs/helpers.js:88:18) May 08 13:00:36 volumio volumio[1018]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) May 08 13:00:36 volumio volumio[1018]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) May 08 13:00:36 volumio volumio[1018]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 08 13:00:36 volumio volumio[1018]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 08 13:00:36 volumio volumio[1018]: error: !!!! WARNING !!!! May 08 13:00:36 volumio volumio[1018]: info: Done. May 08 13:00:41 volumio volumio[1018]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 13:00:43 volumio systemd[1]: tft24-display-startup.service: Service RestartSec=10s expired, scheduling restart. May 08 13:00:43 volumio systemd[1]: tft24-display-startup.service: Scheduled restart job, restart counter is at 31. May 08 13:00:43 volumio systemd[1]: Stopped Volumio Display Startup Message Daemon. May 08 13:00:43 volumio systemd[1]: Started Volumio Display Startup Message Daemon. May 08 13:00:43 volumio VolumioTftDisplay[2070]: Traceback (most recent call last): May 08 13:00:43 volumio VolumioTftDisplay[2070]: File "/data/plugins/user_interface/tft24-display/service/tft24-display-startup.py", line 9, in May 08 13:00:43 volumio VolumioTftDisplay[2070]: from PIL import ImageFont May 08 13:00:43 volumio VolumioTftDisplay[2070]: ImportError: No module named PIL May 08 13:00:43 volumio systemd[1]: tft24-display-startup.service: Main process exited, code=exited, status=1/FAILURE May 08 13:00:43 volumio systemd[1]: tft24-display-startup.service: Failed with result 'exit-code'. May 08 13:00:43 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 08 13:00:43 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 08 13:00:53 volumio systemd[1]: tft24-display-startup.service: Service RestartSec=10s expired, scheduling restart. May 08 13:00:53 volumio systemd[1]: tft24-display-startup.service: Scheduled restart job, restart counter is at 32. May 08 13:00:53 volumio systemd[1]: Stopped Volumio Display Startup Message Daemon. May 08 13:00:53 volumio systemd[1]: Started Volumio Display Startup Message Daemon. May 08 13:00:53 volumio VolumioTftDisplay[2086]: Traceback (most recent call last): May 08 13:00:53 volumio VolumioTftDisplay[2086]: File "/data/plugins/user_interface/tft24-display/service/tft24-display-startup.py", line 9, in May 08 13:00:53 volumio VolumioTftDisplay[2086]: from PIL import ImageFont May 08 13:00:53 volumio VolumioTftDisplay[2086]: ImportError: No module named PIL May 08 13:00:53 volumio systemd[1]: tft24-display-startup.service: Main process exited, code=exited, status=1/FAILURE May 08 13:00:53 volumio systemd[1]: tft24-display-startup.service: Failed with result 'exit-code'. May 08 13:00:58 volumio go-librespot[1211]: time="2025-05-08T13:00:58+02:00" level=trace msg="sent dealer ping" May 08 13:00:58 volumio go-librespot[1211]: time="2025-05-08T13:00:58+02:00" level=trace msg="received dealer pong" May 08 13:01:03 volumio systemd[1]: tft24-display-startup.service: Service RestartSec=10s expired, scheduling restart. May 08 13:01:03 volumio systemd[1]: tft24-display-startup.service: Scheduled restart job, restart counter is at 33. May 08 13:01:03 volumio systemd[1]: Stopped Volumio Display Startup Message Daemon. May 08 13:01:03 volumio systemd[1]: Started Volumio Display Startup Message Daemon. May 08 13:01:03 volumio VolumioTftDisplay[2104]: Traceback (most recent call last): May 08 13:01:03 volumio VolumioTftDisplay[2104]: File "/data/plugins/user_interface/tft24-display/service/tft24-display-startup.py", line 9, in May 08 13:01:03 volumio VolumioTftDisplay[2104]: from PIL import ImageFont May 08 13:01:03 volumio VolumioTftDisplay[2104]: ImportError: No module named PIL May 08 13:01:03 volumio systemd[1]: tft24-display-startup.service: Main process exited, code=exited, status=1/FAILURE May 08 13:01:03 volumio systemd[1]: tft24-display-startup.service: Failed with result 'exit-code'. May 08 13:01:10 volumio go-librespot[1211]: time="2025-05-08T13:01:10+02:00" level=debug msg="dealer connection closed" May 08 13:01:10 volumio go-librespot[1211]: time="2025-05-08T13:01:10+02:00" level=debug msg="dealer recv loop stopped" May 08 13:01:10 volumio go-librespot[1211]: time="2025-05-08T13:01:10+02:00" level=debug msg="obtained new client token: AAC4SUA8nT01cyI8bOZpAaNQW5K9IT5k4ay454ULQJ3xEkA+UXidYQjskvsardxhaIkcGViIebo+5lZ+5+cOmjQooGQqdLl5aAyti3xKfiOqKvQaSAec4Z9FmigKMwYVztDRMOXTN8ugMxlP5Ah54XopHxejMvLh2rPFCAOJiITIofoag50E05cqOYs59nhGh7OttbRf9ByYF+b5FrkFKmP3ELKXRmZygtyNIULTKUI+ysORpng60xZa" May 08 13:01:10 volumio go-librespot[1211]: time="2025-05-08T13:01:10+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" May 08 13:01:10 volumio go-librespot[1211]: time="2025-05-08T13:01:10+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" May 08 13:01:10 volumio go-librespot[1211]: time="2025-05-08T13:01:10+02:00" level=debug msg="completed keyexchange" May 08 13:01:10 volumio go-librespot[1211]: time="2025-05-08T13:01:10+02:00" level=debug msg="completed challenge" May 08 13:01:10 volumio go-librespot[1211]: time="2025-05-08T13:01:10+02:00" level=info msg="authenticated AP as 31rti62k4j4cexf6jyjdh5f5rmay" May 08 13:01:10 volumio go-librespot[1211]: time="2025-05-08T13:01:10+02:00" level=info msg="authenticated Login5 as 31rti62k4j4cexf6jyjdh5f5rmay" May 08 13:01:10 volumio go-librespot[1211]: time="2025-05-08T13:01:10+02:00" level=info msg="accepted zeroconf user 31rti62k4j4cexf6jyjdh5f5rmay from DESKTOP-B5L2STU" May 08 13:01:11 volumio go-librespot[1211]: time="2025-05-08T13:01:11+02:00" level=debug msg="dealer connection opened" May 08 13:01:11 volumio go-librespot[1211]: time="2025-05-08T13:01:11+02:00" level=trace msg="starting accesspoint recv loop" May 08 13:01:11 volumio go-librespot[1211]: time="2025-05-08T13:01:11+02:00" level=trace msg="starting dealer recv loop" May 08 13:01:11 volumio go-librespot[1211]: time="2025-05-08T13:01:11+02:00" level=trace msg="received accesspoint ping" May 08 13:01:11 volumio go-librespot[1211]: time="2025-05-08T13:01:11+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 08 13:01:11 volumio go-librespot[1211]: time="2025-05-08T13:01:11+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 08 13:01:11 volumio go-librespot[1211]: time="2025-05-08T13:01:11+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 08 13:01:11 volumio go-librespot[1211]: time="2025-05-08T13:01:11+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 08 13:01:11 volumio go-librespot[1211]: time="2025-05-08T13:01:11+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" May 08 13:01:11 volumio go-librespot[1211]: time="2025-05-08T13:01:11+02:00" level=debug msg="received connection id: NTNiZGM0Y2EtY2YwYS00N2QyLTkwZDctYzhmMGQxYjUzNDEyK2RlYWxlcit0Y3A6Ly8wYWNhNDExZS5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArRTNDMjMyQzUzN0ZDMEY3RTgwM0JCRTU0N0RGRDY3ODZBNjI3QjM5MzAwQjM4RjU5Rjc3RENFREM5MEU1NjMyMQ==" May 08 13:01:11 volumio go-librespot[1211]: time="2025-05-08T13:01:11+02:00" level=trace msg="received accesspoint pong ack" May 08 13:01:11 volumio go-librespot[1211]: time="2025-05-08T13:01:11+02:00" level=debug msg="put connect state because NEW_DEVICE" May 08 13:01:11 volumio go-librespot[1211]: time="2025-05-08T13:01:11+02:00" level=debug msg="handling transfer player command from fdc3d1639b0d88e00ede686d6b179b381344efb2" May 08 13:01:11 volumio go-librespot[1211]: time="2025-05-08T13:01:11+02:00" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1E37PCcJmCwO2R" May 08 13:01:11 volumio go-librespot[1211]: time="2025-05-08T13:01:11+02:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:playlist:37i9dQZF1E37PCcJmCwO2R" May 08 13:01:11 volumio go-librespot[1211]: time="2025-05-08T13:01:11+02:00" level=debug msg="loading track (paused: false, position: 11798ms)" uri="spotify:track:4T6HLdP6OcAtqC6tGnQelG" May 08 13:01:11 volumio go-librespot[1211]: time="2025-05-08T13:01:11+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 08 13:01:11 volumio go-librespot[1211]: time="2025-05-08T13:01:11+02:00" level=trace msg="emitting websocket event: will_play" May 08 13:01:11 volumio volumio[1018]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4T6HLdP6OcAtqC6tGnQelG","play_origin":"playlist"}} May 08 13:01:11 volumio go-librespot[1211]: time="2025-05-08T13:01:11+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" May 08 13:01:11 volumio go-librespot[1211]: time="2025-05-08T13:01:11+02:00" level=debug msg="selected format OGG_VORBIS_320 (24a6ed72344587ccd52ed8f949ebc0b58d36f78f)" uri="spotify:track:4T6HLdP6OcAtqC6tGnQelG" May 08 13:01:11 volumio go-librespot[1211]: time="2025-05-08T13:01:11+02:00" level=debug msg="requested aes key for file 24a6ed72344587ccd52ed8f949ebc0b58d36f78f, gid: 4T6HLdP6OcAtqC6tGnQelG" May 08 13:01:11 volumio go-librespot[1211]: time="2025-05-08T13:01:11+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1362" May 08 13:01:12 volumio go-librespot[1211]: time="2025-05-08T13:01:12+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1125" May 08 13:01:12 volumio go-librespot[1211]: time="2025-05-08T13:01:12+02:00" level=debug msg="fetched first chunk of 18, total size is 9146260 bytes" uri="spotify:track:4T6HLdP6OcAtqC6tGnQelG" May 08 13:01:12 volumio go-librespot[1211]: time="2025-05-08T13:01:12+02:00" level=trace msg="seek to 11798ms (diff: 120ms, samples: 520291, bytes: 420981)" uri="spotify:track:4T6HLdP6OcAtqC6tGnQelG" May 08 13:01:12 volumio go-librespot[1211]: time="2025-05-08T13:01:12+02:00" level=debug msg="created new output device" May 08 13:01:12 volumio go-librespot[1211]: time="2025-05-08T13:01:12+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames" May 08 13:01:12 volumio go-librespot[1211]: time="2025-05-08T13:01:12+02:00" level=info msg="loaded track \"Everything\" (paused: false, position: 11798ms, duration: 212373ms, prefetched: false)" uri="spotify:track:4T6HLdP6OcAtqC6tGnQelG" May 08 13:01:12 volumio go-librespot[1211]: time="2025-05-08T13:01:12+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 08 13:01:12 volumio go-librespot[1211]: time="2025-05-08T13:01:12+02:00" level=trace msg="scheduling prefetch in 171s" May 08 13:01:12 volumio go-librespot[1211]: time="2025-05-08T13:01:12+02:00" level=trace msg="emitting websocket event: metadata" May 08 13:01:12 volumio go-librespot[1211]: time="2025-05-08T13:01:12+02:00" level=trace msg="emitting websocket event: active" May 08 13:01:12 volumio go-librespot[1211]: time="2025-05-08T13:01:12+02:00" level=debug msg="sending successful reply for dealer request" May 08 13:01:12 volumio go-librespot[1211]: time="2025-05-08T13:01:12+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update May 08 13:01:12 volumio volumio[1018]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4T6HLdP6OcAtqC6tGnQelG","name":"Everything","artist_names":["Michael Bublé"],"album_name":"Call Me Irresponsible","album_cover_url":"https://i.scdn.co/image/ab67616d00001e022ceedc8c879a1f6784fbeef5","position":11798,"duration":212373,"release_date":"year:2007 month:4 day:27","track_number":9,"disc_number":1}} May 08 13:01:12 volumio go-librespot[1211]: time="2025-05-08T13:01:12+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update May 08 13:01:12 volumio go-librespot[1211]: time="2025-05-08T13:01:12+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update May 08 13:01:12 volumio volumio[1018]: SPOTIFY: received: {"type":"active","data":null} May 08 13:01:12 volumio volumio[1018]: info: Aligning Spotify Volume to Volumio Volume May 08 13:01:12 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState May 08 13:01:12 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 May 08 13:01:12 volumio volumio[1018]: info: Setting Spotify Volume from Volumio: 86 May 08 13:01:12 volumio go-librespot[1211]: time="2025-05-08T13:01:12+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 08 13:01:12 volumio go-librespot[1211]: time="2025-05-08T13:01:12+02:00" level=trace msg="emitting websocket event: playing" May 08 13:01:12 volumio volumio[1018]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:4T6HLdP6OcAtqC6tGnQelG","play_origin":"playlist"}} May 08 13:01:12 volumio volumio[1018]: info: Spotify is playing in volatile mode May 08 13:01:12 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 13:01:12 volumio volumio[1018]: SPOTIFY: UNSET VOLATILE May 08 13:01:12 volumio volumio[1018]: SPOTIFY: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":86,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":null,"repeat":null,"repeatSingle":false,"updatedb":false,"consume":false} May 08 13:01:12 volumio volumio[1018]: SPOTIFY: PUSH STATE SPOTIFY May 08 13:01:12 volumio volumio[1018]: SPOTIFY: {"status":"play","service":"spop","title":"Everything","artist":"Michael Bublé","album":"Call Me Irresponsible","albumart":"https://i.scdn.co/image/ab67616d00001e022ceedc8c879a1f6784fbeef5","uri":"spotify:track:4T6HLdP6OcAtqC6tGnQelG","trackType":"spotify","seek":11798,"duration":212,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 08 13:01:12 volumio volumio[1018]: info: CoreCommandRouter::servicePushState May 08 13:01:12 volumio volumio[1018]: info: CoreStateMachine::pushState May 08 13:01:12 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 13:01:12 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState May 08 13:01:12 volumio volumio[1018]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 13:01:12 volumio volumio[1018]: TypeError: Cannot read property 'pushState' of null May 08 13:01:12 volumio volumio[1018]: at /volumio/app/index.js:326:32 May 08 13:01:12 volumio volumio[1018]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) May 08 13:01:12 volumio volumio[1018]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) May 08 13:01:12 volumio volumio[1018]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) May 08 13:01:12 volumio volumio[1018]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:530:22) May 08 13:01:12 volumio volumio[1018]: at CoreStateMachine.syncState (/volumio/app/statemachine.js:591:10) May 08 13:01:12 volumio volumio[1018]: at CoreCommandRouter.servicePushState (/volumio/app/index.js:414:28) May 08 13:01:12 volumio volumio[1018]: at ControllerSpotify.pushState (/data/plugins/music_service/spop/index.js:433:31) May 08 13:01:12 volumio volumio[1018]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:323:14) May 08 13:01:12 volumio volumio[1018]: at WebSocket.message (/data/plugins/music_service/spop/index.js:193:14) May 08 13:01:12 volumio volumio[1018]: at WebSocket.emit (events.js:315:20) May 08 13:01:12 volumio volumio[1018]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) May 08 13:01:12 volumio volumio[1018]: at Receiver.emit (events.js:315:20) May 08 13:01:12 volumio volumio[1018]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) May 08 13:01:12 volumio volumio[1018]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) May 08 13:01:12 volumio volumio[1018]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) May 08 13:01:12 volumio volumio[1018]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 13:01:12 volumio go-librespot[1211]: time="2025-05-08T13:01:12+02:00" level=debug msg="fetched chunk 2/17, size: 524288" uri="spotify:track:4T6HLdP6OcAtqC6tGnQelG" May 08 13:01:13 volumio go-librespot[1211]: time="2025-05-08T13:01:13+02:00" level=debug msg="fetched chunk 1/17, size: 524288" uri="spotify:track:4T6HLdP6OcAtqC6tGnQelG" May 08 13:01:13 volumio go-librespot[1211]: time="2025-05-08T13:01:13+02:00" level=debug msg="fetched chunk 3/17, size: 524288" uri="spotify:track:4T6HLdP6OcAtqC6tGnQelG" May 08 13:01:13 volumio sudo[2130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-08 13:00 May 08 13:01:13 volumio sudo[2130]: 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"