Feb 21 21:20:00 mcintosh systemd[1]: Starting systemd-tmpfiles-clean.service - Cleanup of Temporary Directories... Feb 21 21:20:00 mcintosh systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully. Feb 21 21:20:00 mcintosh systemd[1]: Finished systemd-tmpfiles-clean.service - Cleanup of Temporary Directories. Feb 21 21:20:00 mcintosh systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dclean.service.mount: Deactivated successfully. Feb 21 21:20:02 mcintosh volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 21 21:20:02 mcintosh volumio[1198]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 21 21:20:02 mcintosh volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 21 21:20:02 mcintosh volumio[1198]: info: Received Get System Version Feb 21 21:20:02 mcintosh volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 21:20:02 mcintosh volumio[1198]: info: Received Get System Info Feb 21 21:20:02 mcintosh volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 21:20:02 mcintosh volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 21:20:02 mcintosh volumio[1198]: info: Discovery: Getting this device information Feb 21 21:20:02 mcintosh volumio[1198]: info: CoreCommandRouter::volumioGetState Feb 21 21:20:02 mcintosh volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 21:20:09 mcintosh go-librespot[1921]: time="2026-02-21T21:20:09-05:00" level=debug msg="fetched chunk 10/12, size: 524288" uri="spotify:track:05lbdtCYfu1tDdagYVggOO" Feb 21 21:20:22 mcintosh go-librespot[1921]: time="2026-02-21T21:20:22-05:00" level=debug msg="fetched chunk 11/12, size: 524288" uri="spotify:track:05lbdtCYfu1tDdagYVggOO" Feb 21 21:20:22 mcintosh go-librespot[1921]: time="2026-02-21T21:20:22-05:00" level=trace msg="sent dealer ping" Feb 21 21:20:22 mcintosh go-librespot[1921]: time="2026-02-21T21:20:22-05:00" level=trace msg="received dealer pong" Feb 21 21:20:34 mcintosh go-librespot[1921]: time="2026-02-21T21:20:34-05:00" level=debug msg="fetched chunk 12/12, size: 27616" uri="spotify:track:05lbdtCYfu1tDdagYVggOO" Feb 21 21:20:47 mcintosh go-librespot[1921]: time="2026-02-21T21:20:47-05:00" level=debug msg="prefetching next track" uri="spotify:track:2Naa6x9ieVXhvNCDM6NL1l" Feb 21 21:20:47 mcintosh go-librespot[1921]: time="2026-02-21T21:20:47-05:00" level=debug msg="selected format OGG_VORBIS_320 (7fe8ff548ac8e929d6081d417aaf8e7bd6d2e0c1)" uri="spotify:track:2Naa6x9ieVXhvNCDM6NL1l" Feb 21 21:20:47 mcintosh go-librespot[1921]: time="2026-02-21T21:20:47-05:00" level=debug msg="requested aes key for file 7fe8ff548ac8e929d6081d417aaf8e7bd6d2e0c1, gid: 2Naa6x9ieVXhvNCDM6NL1l" Feb 21 21:20:47 mcintosh go-librespot[1921]: time="2026-02-21T21:20:47-05:00" level=trace msg="found 2 cdn urls" uri="spotify:track:2Naa6x9ieVXhvNCDM6NL1l" Feb 21 21:20:47 mcintosh go-librespot[1921]: time="2026-02-21T21:20:47-05:00" level=debug msg="fetched first chunk of 18, total size is 9282923 bytes" uri="spotify:track:2Naa6x9ieVXhvNCDM6NL1l" Feb 21 21:20:47 mcintosh go-librespot[1921]: time="2026-02-21T21:20:47-05:00" level=info msg="prefetched track \"waves\" (duration: 248197ms)" uri="spotify:track:2Naa6x9ieVXhvNCDM6NL1l" Feb 21 21:20:47 mcintosh go-librespot[1921]: time="2026-02-21T21:20:47-05:00" level=debug msg="fetched chunk 1/17, size: 524288" uri="spotify:track:2Naa6x9ieVXhvNCDM6NL1l" Feb 21 21:20:47 mcintosh go-librespot[1921]: time="2026-02-21T21:20:47-05:00" level=debug msg="fetched chunk 3/17, size: 524288" uri="spotify:track:2Naa6x9ieVXhvNCDM6NL1l" Feb 21 21:20:47 mcintosh go-librespot[1921]: time="2026-02-21T21:20:47-05:00" level=debug msg="fetched chunk 2/17, size: 524288" uri="spotify:track:2Naa6x9ieVXhvNCDM6NL1l" Feb 21 21:20:52 mcintosh go-librespot[1921]: time="2026-02-21T21:20:52-05:00" level=trace msg="sent dealer ping" Feb 21 21:20:52 mcintosh go-librespot[1921]: time="2026-02-21T21:20:52-05:00" level=trace msg="received dealer pong" Feb 21 21:21:02 mcintosh volumio[1198]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ferrum_streaming_control_technology/volumio/bookworm/amd64 Feb 21 21:21:02 mcintosh volumio5-onboarding[1848]: time=2026-02-21T21:21:02.711-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 21 21:21:03 mcintosh volumio[1198]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ferrum_streaming_control_technology/volumio/bookworm/amd64 Feb 21 21:21:03 mcintosh volumio[1198]: info: Folder /tmp/plugins removed Feb 21 21:21:03 mcintosh volumio5-onboarding[1848]: time=2026-02-21T21:21:03.747-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 21 21:21:03 mcintosh volumio5-onboarding[1848]: time=2026-02-21T21:21:03.801-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 21 21:21:03 mcintosh volumio[1198]: info: Check plugin dependencies Feb 21 21:21:03 mcintosh volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 21:21:03 mcintosh volumio5-onboarding[1848]: time=2026-02-21T21:21:03.819-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 21 21:21:03 mcintosh volumio[1198]: info: Checking if plugin already exists Feb 21 21:21:03 mcintosh volumio5-onboarding[1848]: time=2026-02-21T21:21:03.822-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 21 21:21:03 mcintosh volumio[1198]: info: Rename folder Feb 21 21:21:03 mcintosh volumio[1198]: info: Folder /tmp/downloaded_plugin.zip removed Feb 21 21:21:03 mcintosh volumio5-onboarding[1848]: time=2026-02-21T21:21:03.833-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 21 21:21:03 mcintosh volumio[1198]: info: Move to category Feb 21 21:21:03 mcintosh volumio5-onboarding[1848]: time=2026-02-21T21:21:03.875-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 21 21:21:03 mcintosh volumio[1198]: info: Checking if install.sh is present Feb 21 21:21:03 mcintosh volumio[1198]: info: Executing install.sh Feb 21 21:21:03 mcintosh sudo[4776]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/ferrum_streaming_control_technology/install.sh Feb 21 21:21:03 mcintosh sudo[4776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:21:03 mcintosh sudo[4776]: pam_unix(sudo:session): session closed for user root Feb 21 21:21:03 mcintosh volumio[1198]: info: Installing ferrum streaming control technology Dependencies Feb 21 21:21:03 mcintosh volumio5-onboarding[1848]: time=2026-02-21T21:21:03.900-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 21 21:21:03 mcintosh volumio[1198]: Plugin install end detected on script Feb 21 21:21:03 mcintosh volumio[1198]: info: Install script completed Feb 21 21:21:03 mcintosh volumio5-onboarding[1848]: time=2026-02-21T21:21:03.903-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 21 21:21:03 mcintosh volumio[1198]: info: Adding reference to registry Feb 21 21:21:03 mcintosh volumio5-onboarding[1848]: time=2026-02-21T21:21:03.906-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 21 21:21:03 mcintosh volumio[1198]: info: Done installing plugin. Feb 21 21:21:03 mcintosh volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 21:21:03 mcintosh volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 21 21:21:03 mcintosh volumio[1198]: info: Folder /tmp/plugins removed Feb 21 21:21:03 mcintosh volumio[1198]: info: Folder /tmp/downloaded_plugin.zip removed Feb 21 21:21:03 mcintosh volumio[1198]: info: Folder /data/temp removed Feb 21 21:21:05 mcintosh volumio[1198]: info: Enabling plugin ferrum_streaming_control_technology Feb 21 21:21:05 mcintosh volumio[1198]: info: Loading plugin "ferrum_streaming_control_technology"... Feb 21 21:21:05 mcintosh volumio[1198]: error: !!!! WARNING !!!! Feb 21 21:21:05 mcintosh volumio[1198]: error: The plugin user_interface/ferrum_streaming_control_technology failed to load, setting it to stopped. Error: Error: Cannot find module '@hemspzoo/fsct-lib-linux-x64-gnu' Feb 21 21:21:05 mcintosh volumio[1198]: Require stack: Feb 21 21:21:05 mcintosh volumio[1198]: - /data/plugins/user_interface/ferrum_streaming_control_technology/node_modules/@hemspzoo/fsct-lib/index.js Feb 21 21:21:05 mcintosh volumio[1198]: - /data/plugins/user_interface/ferrum_streaming_control_technology/index.js Feb 21 21:21:05 mcintosh volumio[1198]: - /volumio/app/pluginmanager.js Feb 21 21:21:05 mcintosh volumio[1198]: - /volumio/app/index.js Feb 21 21:21:05 mcintosh volumio[1198]: - /volumio/index.js Feb 21 21:21:05 mcintosh volumio[1198]: error: Stack trace: Error: Cannot find module '@hemspzoo/fsct-lib-linux-x64-gnu' Feb 21 21:21:05 mcintosh volumio[1198]: Require stack: Feb 21 21:21:05 mcintosh volumio[1198]: - /data/plugins/user_interface/ferrum_streaming_control_technology/node_modules/@hemspzoo/fsct-lib/index.js Feb 21 21:21:05 mcintosh volumio[1198]: - /data/plugins/user_interface/ferrum_streaming_control_technology/index.js Feb 21 21:21:05 mcintosh volumio[1198]: - /volumio/app/pluginmanager.js Feb 21 21:21:05 mcintosh volumio[1198]: - /volumio/app/index.js Feb 21 21:21:05 mcintosh volumio[1198]: - /volumio/index.js Feb 21 21:21:05 mcintosh volumio[1198]: at Module._resolveFilename (node:internal/modules/cjs/loader:1048:15) Feb 21 21:21:05 mcintosh volumio[1198]: at Module._load (node:internal/modules/cjs/loader:901:27) Feb 21 21:21:05 mcintosh volumio[1198]: at Module.require (node:internal/modules/cjs/loader:1115:19) Feb 21 21:21:05 mcintosh volumio[1198]: at require (node:internal/modules/helpers:130:18) Feb 21 21:21:05 mcintosh volumio[1198]: at Object. (/data/plugins/user_interface/ferrum_streaming_control_technology/node_modules/@hemspzoo/fsct-lib/index.js:190:31) Feb 21 21:21:05 mcintosh volumio[1198]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Feb 21 21:21:05 mcintosh volumio[1198]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Feb 21 21:21:05 mcintosh volumio[1198]: at Module.load (node:internal/modules/cjs/loader:1091:32) Feb 21 21:21:05 mcintosh volumio[1198]: at Module._load (node:internal/modules/cjs/loader:938:12) Feb 21 21:21:05 mcintosh volumio[1198]: at Module.require (node:internal/modules/cjs/loader:1115:19) Feb 21 21:21:05 mcintosh volumio[1198]: at require (node:internal/modules/helpers:130:18) Feb 21 21:21:05 mcintosh volumio[1198]: at Object. (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:25:118) Feb 21 21:21:05 mcintosh volumio[1198]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Feb 21 21:21:05 mcintosh volumio[1198]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Feb 21 21:21:05 mcintosh volumio[1198]: at Module.load (node:internal/modules/cjs/loader:1091:32) Feb 21 21:21:05 mcintosh volumio[1198]: at Module._load (node:internal/modules/cjs/loader:938:12) Feb 21 21:21:05 mcintosh volumio[1198]: error: !!!! WARNING !!!! Feb 21 21:21:05 mcintosh volumio[1198]: info: Done. Feb 21 21:21:08 mcintosh go-librespot[1921]: time="2026-02-21T21:21:08-05:00" level=trace msg="received accesspoint ping" Feb 21 21:21:08 mcintosh go-librespot[1921]: time="2026-02-21T21:21:08-05:00" level=trace msg="received accesspoint pong ack" Feb 21 21:21:13 mcintosh volumio[1198]: info: Enabling plugin ferrum_streaming_control_technology Feb 21 21:21:13 mcintosh volumio[1198]: info: Loading plugin "ferrum_streaming_control_technology"... Feb 21 21:21:13 mcintosh volumio[1198]: error: !!!! WARNING !!!! Feb 21 21:21:13 mcintosh volumio[1198]: error: The plugin user_interface/ferrum_streaming_control_technology failed to load, setting it to stopped. Error: Error: Cannot find module '@hemspzoo/fsct-lib-linux-x64-gnu' Feb 21 21:21:13 mcintosh volumio[1198]: Require stack: Feb 21 21:21:13 mcintosh volumio[1198]: - /data/plugins/user_interface/ferrum_streaming_control_technology/node_modules/@hemspzoo/fsct-lib/index.js Feb 21 21:21:13 mcintosh volumio[1198]: - /data/plugins/user_interface/ferrum_streaming_control_technology/index.js Feb 21 21:21:13 mcintosh volumio[1198]: - /volumio/app/pluginmanager.js Feb 21 21:21:13 mcintosh volumio[1198]: - /volumio/app/index.js Feb 21 21:21:13 mcintosh volumio[1198]: - /volumio/index.js Feb 21 21:21:13 mcintosh volumio[1198]: error: Stack trace: Error: Cannot find module '@hemspzoo/fsct-lib-linux-x64-gnu' Feb 21 21:21:13 mcintosh volumio[1198]: Require stack: Feb 21 21:21:13 mcintosh volumio[1198]: - /data/plugins/user_interface/ferrum_streaming_control_technology/node_modules/@hemspzoo/fsct-lib/index.js Feb 21 21:21:13 mcintosh volumio[1198]: - /data/plugins/user_interface/ferrum_streaming_control_technology/index.js Feb 21 21:21:13 mcintosh volumio[1198]: - /volumio/app/pluginmanager.js Feb 21 21:21:13 mcintosh volumio[1198]: - /volumio/app/index.js Feb 21 21:21:13 mcintosh volumio[1198]: - /volumio/index.js Feb 21 21:21:13 mcintosh volumio[1198]: at Module._resolveFilename (node:internal/modules/cjs/loader:1048:15) Feb 21 21:21:13 mcintosh volumio[1198]: at Module._load (node:internal/modules/cjs/loader:901:27) Feb 21 21:21:13 mcintosh volumio[1198]: at Module.require (node:internal/modules/cjs/loader:1115:19) Feb 21 21:21:13 mcintosh volumio[1198]: at require (node:internal/modules/helpers:130:18) Feb 21 21:21:13 mcintosh volumio[1198]: at Object. (/data/plugins/user_interface/ferrum_streaming_control_technology/node_modules/@hemspzoo/fsct-lib/index.js:190:31) Feb 21 21:21:13 mcintosh volumio[1198]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Feb 21 21:21:13 mcintosh volumio[1198]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Feb 21 21:21:13 mcintosh volumio[1198]: at Module.load (node:internal/modules/cjs/loader:1091:32) Feb 21 21:21:13 mcintosh volumio[1198]: at Module._load (node:internal/modules/cjs/loader:938:12) Feb 21 21:21:13 mcintosh volumio[1198]: at Module.require (node:internal/modules/cjs/loader:1115:19) Feb 21 21:21:13 mcintosh volumio[1198]: at require (node:internal/modules/helpers:130:18) Feb 21 21:21:13 mcintosh volumio[1198]: at Object. (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:25:118) Feb 21 21:21:13 mcintosh volumio[1198]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Feb 21 21:21:13 mcintosh volumio[1198]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Feb 21 21:21:13 mcintosh volumio[1198]: at Module.load (node:internal/modules/cjs/loader:1091:32) Feb 21 21:21:13 mcintosh volumio[1198]: at Module._load (node:internal/modules/cjs/loader:938:12) Feb 21 21:21:13 mcintosh volumio[1198]: error: !!!! WARNING !!!! Feb 21 21:21:13 mcintosh volumio[1198]: info: Error: TypeError: Cannot read properties of null (reading 'printToastMessage') Feb 21 21:21:15 mcintosh volumio[1198]: info: Enabling plugin ferrum_streaming_control_technology Feb 21 21:21:15 mcintosh volumio[1198]: info: Loading plugin "ferrum_streaming_control_technology"... Feb 21 21:21:15 mcintosh volumio[1198]: error: !!!! WARNING !!!! Feb 21 21:21:15 mcintosh volumio[1198]: error: The plugin user_interface/ferrum_streaming_control_technology failed to load, setting it to stopped. Error: Error: Cannot find module '@hemspzoo/fsct-lib-linux-x64-gnu' Feb 21 21:21:15 mcintosh volumio[1198]: Require stack: Feb 21 21:21:15 mcintosh volumio[1198]: - /data/plugins/user_interface/ferrum_streaming_control_technology/node_modules/@hemspzoo/fsct-lib/index.js Feb 21 21:21:15 mcintosh volumio[1198]: - /data/plugins/user_interface/ferrum_streaming_control_technology/index.js Feb 21 21:21:15 mcintosh volumio[1198]: - /volumio/app/pluginmanager.js Feb 21 21:21:15 mcintosh volumio[1198]: - /volumio/app/index.js Feb 21 21:21:15 mcintosh volumio[1198]: - /volumio/index.js Feb 21 21:21:15 mcintosh volumio[1198]: error: Stack trace: Error: Cannot find module '@hemspzoo/fsct-lib-linux-x64-gnu' Feb 21 21:21:15 mcintosh volumio[1198]: Require stack: Feb 21 21:21:15 mcintosh volumio[1198]: - /data/plugins/user_interface/ferrum_streaming_control_technology/node_modules/@hemspzoo/fsct-lib/index.js Feb 21 21:21:15 mcintosh volumio[1198]: - /data/plugins/user_interface/ferrum_streaming_control_technology/index.js Feb 21 21:21:15 mcintosh volumio[1198]: - /volumio/app/pluginmanager.js Feb 21 21:21:15 mcintosh volumio[1198]: - /volumio/app/index.js Feb 21 21:21:15 mcintosh volumio[1198]: - /volumio/index.js Feb 21 21:21:15 mcintosh volumio[1198]: at Module._resolveFilename (node:internal/modules/cjs/loader:1048:15) Feb 21 21:21:15 mcintosh volumio[1198]: at Module._load (node:internal/modules/cjs/loader:901:27) Feb 21 21:21:15 mcintosh volumio[1198]: at Module.require (node:internal/modules/cjs/loader:1115:19) Feb 21 21:21:15 mcintosh volumio[1198]: at require (node:internal/modules/helpers:130:18) Feb 21 21:21:15 mcintosh volumio[1198]: at Object. (/data/plugins/user_interface/ferrum_streaming_control_technology/node_modules/@hemspzoo/fsct-lib/index.js:190:31) Feb 21 21:21:15 mcintosh volumio[1198]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Feb 21 21:21:15 mcintosh volumio[1198]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Feb 21 21:21:15 mcintosh volumio[1198]: at Module.load (node:internal/modules/cjs/loader:1091:32) Feb 21 21:21:15 mcintosh volumio[1198]: at Module._load (node:internal/modules/cjs/loader:938:12) Feb 21 21:21:15 mcintosh volumio[1198]: at Module.require (node:internal/modules/cjs/loader:1115:19) Feb 21 21:21:15 mcintosh volumio[1198]: at require (node:internal/modules/helpers:130:18) Feb 21 21:21:15 mcintosh volumio[1198]: at Object. (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:25:118) Feb 21 21:21:15 mcintosh volumio[1198]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Feb 21 21:21:15 mcintosh volumio[1198]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Feb 21 21:21:15 mcintosh volumio[1198]: at Module.load (node:internal/modules/cjs/loader:1091:32) Feb 21 21:21:15 mcintosh volumio[1198]: at Module._load (node:internal/modules/cjs/loader:938:12) Feb 21 21:21:15 mcintosh volumio[1198]: error: !!!! WARNING !!!! Feb 21 21:21:15 mcintosh volumio[1198]: info: Error: TypeError: Cannot read properties of null (reading 'printToastMessage') Feb 21 21:21:16 mcintosh go-librespot[1921]: time="2026-02-21T21:21:16-05:00" level=trace msg="emitting websocket event: not_playing" Feb 21 21:21:16 mcintosh go-librespot[1921]: time="2026-02-21T21:21:16-05:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:2Naa6x9ieVXhvNCDM6NL1l" Feb 21 21:21:16 mcintosh volumio[1198]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:album:6y1BndaDRT2FtLEHLg301e","uri":"spotify:track:05lbdtCYfu1tDdagYVggOO","play_origin":"album"}} Feb 21 21:21:16 mcintosh volumio[1198]: error: Failed to decode event: not_playing Feb 21 21:21:17 mcintosh go-librespot[1921]: time="2026-02-21T21:21:17-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 21 21:21:17 mcintosh go-librespot[1921]: time="2026-02-21T21:21:17-05:00" level=trace msg="emitting websocket event: will_play" Feb 21 21:21:17 mcintosh go-librespot[1921]: time="2026-02-21T21:21:17-05:00" level=info msg="loaded track \"waves\" (paused: false, position: 0ms, duration: 248197ms, prefetched: true)" uri="spotify:track:2Naa6x9ieVXhvNCDM6NL1l" Feb 21 21:21:17 mcintosh volumio[1198]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:album:6y1BndaDRT2FtLEHLg301e","uri":"spotify:track:2Naa6x9ieVXhvNCDM6NL1l","play_origin":"album"}} Feb 21 21:21:17 mcintosh go-librespot[1921]: time="2026-02-21T21:21:17-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 21 21:21:17 mcintosh go-librespot[1921]: time="2026-02-21T21:21:17-05:00" level=trace msg="scheduling prefetch in 218s" Feb 21 21:21:17 mcintosh go-librespot[1921]: time="2026-02-21T21:21:17-05:00" level=trace msg="emitting websocket event: metadata" Feb 21 21:21:17 mcintosh volumio[1198]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2Naa6x9ieVXhvNCDM6NL1l","name":"waves","artist_names":["Zach Templar"],"album_name":"in waves","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02b8e92d745850e77385b929b8","position":0,"duration":248197,"release_date":"year:2025 month:4 day:11","track_number":2,"disc_number":1}} Feb 21 21:21:17 mcintosh go-librespot[1921]: time="2026-02-21T21:21:17-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 21 21:21:17 mcintosh go-librespot[1921]: time="2026-02-21T21:21:17-05:00" level=trace msg="emitting websocket event: playing" Feb 21 21:21:17 mcintosh volumio[1198]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:album:6y1BndaDRT2FtLEHLg301e","uri":"spotify:track:2Naa6x9ieVXhvNCDM6NL1l","resume":false,"play_origin":"album"}} Feb 21 21:21:17 mcintosh volumio[1198]: SPOTIFY: PUSH STATE SPOTIFY Feb 21 21:21:17 mcintosh volumio[1198]: SPOTIFY: {"status":"play","service":"spop","title":"waves","artist":"Zach Templar","album":"in waves","albumart":"https://i.scdn.co/image/ab67616d00001e02b8e92d745850e77385b929b8","uri":"spotify:track:2Naa6x9ieVXhvNCDM6NL1l","trackType":"spotify","seek":1000,"duration":248,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Feb 21 21:21:17 mcintosh volumio[1198]: info: CoreCommandRouter::servicePushState Feb 21 21:21:17 mcintosh volumio[1198]: info: CoreStateMachine::pushState Feb 21 21:21:17 mcintosh volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 21:21:17 mcintosh volumio[1198]: info: CoreCommandRouter::volumioPushState Feb 21 21:21:17 mcintosh volumio[1198]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 21:21:17 mcintosh volumio[1198]: TypeError: Cannot read properties of null (reading 'pushState') Feb 21 21:21:17 mcintosh volumio[1198]: at /volumio/app/index.js:326:32 Feb 21 21:21:17 mcintosh volumio[1198]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Feb 21 21:21:17 mcintosh volumio[1198]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Feb 21 21:21:17 mcintosh volumio[1198]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) Feb 21 21:21:17 mcintosh volumio[1198]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:534:22) Feb 21 21:21:17 mcintosh volumio[1198]: at CoreStateMachine.syncState (/volumio/app/statemachine.js:595:10) Feb 21 21:21:17 mcintosh volumio[1198]: at CoreCommandRouter.servicePushState (/volumio/app/index.js:414:28) Feb 21 21:21:17 mcintosh volumio[1198]: at ControllerSpotify.pushState (/data/plugins/music_service/spop/index.js:439:31) Feb 21 21:21:17 mcintosh volumio[1198]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:329:14) Feb 21 21:21:17 mcintosh volumio[1198]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14) Feb 21 21:21:17 mcintosh volumio[1198]: at WebSocket.emit (node:events:514:28) Feb 21 21:21:17 mcintosh volumio[1198]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Feb 21 21:21:17 mcintosh volumio[1198]: at Receiver.emit (node:events:514:28) Feb 21 21:21:17 mcintosh volumio[1198]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Feb 21 21:21:17 mcintosh volumio[1198]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Feb 21 21:21:17 mcintosh volumio[1198]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Feb 21 21:21:17 mcintosh volumio[1198]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 21:21:17 mcintosh sudo[4844]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-21 21:20' Feb 21 21:21:17 mcintosh sudo[4844]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:31:57 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6759e875c98b942866984e844891d6c1"