-- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Wed 2025-12-10 13:27:19 UTC. -- Dec 10 13:26:05 muzik go-librespot[1242]: time="2025-12-10T13:26:05Z" level=debug msg="fetched chunk 22/29, size: 524288" uri="spotify:track:49CAe5ij5fBiAec5C2W3dY" Dec 10 13:26:11 muzik go-librespot[1242]: time="2025-12-10T13:26:11Z" level=trace msg="sent dealer ping" Dec 10 13:26:11 muzik go-librespot[1242]: time="2025-12-10T13:26:11Z" level=trace msg="received dealer pong" Dec 10 13:26:19 muzik go-librespot[1242]: time="2025-12-10T13:26:19Z" level=debug msg="fetched chunk 23/29, size: 524288" uri="spotify:track:49CAe5ij5fBiAec5C2W3dY" Dec 10 13:26:35 muzik go-librespot[1242]: time="2025-12-10T13:26:35Z" level=debug msg="fetched chunk 24/29, size: 524288" uri="spotify:track:49CAe5ij5fBiAec5C2W3dY" Dec 10 13:26:41 muzik go-librespot[1242]: time="2025-12-10T13:26:41Z" level=trace msg="sent dealer ping" Dec 10 13:26:41 muzik go-librespot[1242]: time="2025-12-10T13:26:41Z" level=trace msg="received dealer pong" Dec 10 13:26:42 muzik go-librespot[1242]: time="2025-12-10T13:26:42Z" level=trace msg="received accesspoint ping" Dec 10 13:26:42 muzik go-librespot[1242]: time="2025-12-10T13:26:42Z" level=trace msg="received accesspoint pong ack" Dec 10 13:26:49 muzik go-librespot[1242]: time="2025-12-10T13:26:49Z" level=debug msg="fetched chunk 25/29, size: 524288" uri="spotify:track:49CAe5ij5fBiAec5C2W3dY" Dec 10 13:27:03 muzik go-librespot[1242]: time="2025-12-10T13:27:03Z" level=debug msg="fetched chunk 26/29, size: 524288" uri="spotify:track:49CAe5ij5fBiAec5C2W3dY" Dec 10 13:27:11 muzik go-librespot[1242]: time="2025-12-10T13:27:11Z" level=trace msg="sent dealer ping" Dec 10 13:27:11 muzik go-librespot[1242]: time="2025-12-10T13:27:11Z" level=trace msg="received dealer pong" Dec 10 13:27:13 muzik volumio[861]: info: CoreCommandRouter::volumioPause Dec 10 13:27:13 muzik volumio[861]: info: CoreStateMachine::pause Dec 10 13:27:13 muzik volumio[861]: info: CoreStateMachine::stPlaybackTimer Dec 10 13:27:13 muzik volumio[861]: info: CoreStateMachine::servicePause Dec 10 13:27:13 muzik volumio[861]: info: CorePlayQueue::getTrack 20 Dec 10 13:27:13 muzik volumio[861]: info: CoreCommandRouter::servicePause Dec 10 13:27:13 muzik volumio[861]: info: Spotify Received pause Dec 10 13:27:13 muzik volumio[861]: SPOTIFY: SPOTIFY PAUSE Dec 10 13:27:13 muzik volumio[861]: SPOTIFY: {"status":"play","position":20,"title":"Wind-Up - Live 2004 - Remaster 2025","artist":"Jethro Tull","album":"Aqualung Live (Remaster 2025)","albumart":"https://i.scdn.co/image/ab67616d0000b27374aba0eebfd54a21f360ca20","uri":"spotify:track:49CAe5ij5fBiAec5C2W3dY","seek":0,"duration":399,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"repeat":false,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":true,"volatile":false,"service":"spop"} Dec 10 13:27:13 muzik volumio[861]: info: Sending Spotify command to local API: /player/pause Dec 10 13:27:13 muzik go-librespot[1242]: time="2025-12-10T13:27:13Z" level=debug msg="pause track at 325021ms" Dec 10 13:27:13 muzik go-librespot[1242]: time="2025-12-10T13:27:13Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 10 13:27:13 muzik go-librespot[1242]: time="2025-12-10T13:27:13Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 10 13:27:13 muzik go-librespot[1242]: time="2025-12-10T13:27:13Z" level=trace msg="emitting websocket event: paused" Dec 10 13:27:13 muzik volumio[861]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:track:49CAe5ij5fBiAec5C2W3dY","uri":"spotify:track:49CAe5ij5fBiAec5C2W3dY","play_origin":"go-librespot"}} Dec 10 13:27:13 muzik volumio[861]: SPOTIFY: PUSH STATE SPOTIFY Dec 10 13:27:13 muzik volumio[861]: SPOTIFY: {"status":"pause","service":"spop","title":"Wind-Up - Live 2004 - Remaster 2025","artist":"Jethro Tull","album":"Aqualung Live (Remaster 2025)","albumart":"https://i.scdn.co/image/ab67616d00001e0274aba0eebfd54a21f360ca20","uri":"spotify:track:49CAe5ij5fBiAec5C2W3dY","trackType":"spotify","seek":325000,"duration":399,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 10 13:27:13 muzik volumio[861]: info: CoreCommandRouter::servicePushState Dec 10 13:27:13 muzik volumio[861]: info: CorePlayQueue::getTrack 20 Dec 10 13:27:13 muzik volumio[861]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Wind-Up - Live 2004 - Remaster 2025","artist":"Jethro Tull","album":"Aqualung Live (Remaster 2025)","albumart":"https://i.scdn.co/image/ab67616d00001e0274aba0eebfd54a21f360ca20","uri":"spotify:track:49CAe5ij5fBiAec5C2W3dY","trackType":"spotify","seek":325000,"duration":399,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 10 13:27:13 muzik volumio[861]: verbose: CURRENT POSITION 20 Dec 10 13:27:13 muzik volumio[861]: info: CoreStateMachine::syncState stateService pause Dec 10 13:27:13 muzik volumio[861]: info: CoreStateMachine::syncState currentStatus pause Dec 10 13:27:13 muzik volumio[861]: info: CoreStateMachine::pushState Dec 10 13:27:13 muzik volumio[861]: info: CorePlayQueue::getTrack 20 Dec 10 13:27:13 muzik volumio[861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 13:27:13 muzik volumio[861]: info: CoreCommandRouter::volumioPushState Dec 10 13:27:13 muzik volumio[861]: info: CoreCommandRouter::volumioGetState Dec 10 13:27:13 muzik volumio[861]: info: CorePlayQueue::getTrack 20 Dec 10 13:27:13 muzik volumio[861]: info: CoreStateMachine::stPlaybackTimer Dec 10 13:27:13 muzik volumio[861]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Dec 10 13:27:13 muzik volumio[861]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 10 13:27:13 muzik volumio[861]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Dec 10 13:27:13 muzik volumio[861]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 10 13:27:19 muzik volumio[861]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Dec 10 13:27:19 muzik volumio[861]: info: PLUGIN onReboot : networkfs Dec 10 13:27:19 muzik volumio[861]: info: PLUGIN onReboot : audiophonicsonoff Dec 10 13:27:19 muzik volumio[861]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 13:27:19 muzik volumio[861]: TypeError: Cannot read property 'writeSync' of undefined Dec 10 13:27:19 muzik volumio[861]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Dec 10 13:27:19 muzik volumio[861]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Dec 10 13:27:19 muzik volumio[861]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Dec 10 13:27:19 muzik volumio[861]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 10 13:27:19 muzik volumio[861]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 10 13:27:19 muzik volumio[861]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Dec 10 13:27:19 muzik volumio[861]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) Dec 10 13:27:19 muzik volumio[861]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:870:33) Dec 10 13:27:19 muzik volumio[861]: at Socket.emit (events.js:315:20) Dec 10 13:27:19 muzik volumio[861]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Dec 10 13:27:19 muzik volumio[861]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Dec 10 13:27:19 muzik volumio[861]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 13:27:19 muzik sudo[15573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-10 13:26 Dec 10 13:27:19 muzik sudo[15573]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 06:58:02 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4ae6504ed6f22e44c01a6e93b16badbd"