-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Tue 2025-05-13 20:58:26 EDT. -- May 13 20:57:17 volumio volumio[1076]: verbose: New Socket.io Connection to 192.168.1.132 from 192.168.1.133 UA: Mozilla/5.0 (Linux; Android 13; SM-S515DL Build/TP1A.220624.014; 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: 6 May 13 20:57:18 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 13 20:57:18 volumio volumio[1076]: info: CoreCommandRouter::volumioGetVisibleSources May 13 20:57:18 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 20:57:18 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState May 13 20:57:18 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 13 20:57:18 volumio volumio[1076]: info: CoreCommandRouter::volumioGetQueue May 13 20:57:18 volumio volumio[1076]: info: CoreStateMachine::getQueue May 13 20:57:18 volumio volumio[1076]: info: CorePlayQueue::getQueue May 13 20:57:18 volumio volumio[1076]: info: Listing playlists May 13 20:57:18 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 13 20:57:18 volumio volumio[1076]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 13 20:57:18 volumio volumio[1076]: info: Received Get System Info May 13 20:57:18 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 20:57:18 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 20:57:18 volumio volumio[1076]: info: Discovery: Getting this device information May 13 20:57:18 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState May 13 20:57:18 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 20:57:18 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState May 13 20:57:19 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 13 20:57:19 volumio volumio[1076]: info: CoreCommandRouter::getUIConfigOnPlugin May 13 20:57:19 volumio volumio[1076]: info: CoreCommandRouter::getUIConfigOnPlugin May 13 20:57:19 volumio volumio[1076]: info: CoreCommandRouter::getUIConfigOnPlugin May 13 20:57:19 volumio volumio[1076]: info: FusionDsp - Sennheiser hd 600 -1.0 tilt May 13 20:57:19 volumio volumio[1076]: info: FusionDsp - Sennheiser hd 600 -1.0 tilt May 13 20:57:19 volumio volumio[1076]: info: FusionDsp - Sennheiser hd 600 -1.0 tilt May 13 20:57:19 volumio volumio[1076]: info: FusionDsp - .json,Hifiman he 400se stealth.json,Sennheiser hd 600 -1.0 tilt.json,Tilt 3 basic -3.0.json,Tilt 6 basic -6.0.json,sennheiser hd 600.json May 13 20:57:20 volumio volumio[1076]: info: FusionDsp - .json,Hifiman he 400se stealth.json,Sennheiser hd 600 -1.0 tilt.json,Tilt 3 basic -3.0.json,Tilt 6 basic -6.0.json,sennheiser hd 600.json May 13 20:57:20 volumio volumio[1076]: info: FusionDsp - .json,Hifiman he 400se stealth.json,Sennheiser hd 600 -1.0 tilt.json,Tilt 3 basic -3.0.json,Tilt 6 basic -6.0.json,sennheiser hd 600.json May 13 20:57:20 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 20:57:20 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 20:57:20 volumio volumio[1076]: info: Discovery: Getting this device information May 13 20:57:20 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState May 13 20:57:20 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 20:57:20 volumio wpa_supplicant[919]: TDLS: Creating peer entry for 5a:8a:ca:9d:72:da May 13 20:57:20 volumio wpa_supplicant[919]: TDLS: Dialog Token in TPK M1 19 May 13 20:57:20 volumio wpa_supplicant[919]: nl80211: kernel reports: key addition failed May 13 20:57:20 volumio wpa_supplicant[919]: TDLS: Failed to set TPK to the driver May 13 20:57:49 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 20:57:49 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 20:57:49 volumio volumio[1076]: info: Discovery: Getting this device information May 13 20:57:49 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState May 13 20:57:49 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 20:57:53 volumio volumio-remote-updater[615]: zsync done May 13 20:57:53 volumio volumio-remote-updater[615]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "2m" May 13 20:57:53 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 13 20:57:53 volumio volumio[1076]: updateProgress May 13 20:57:53 volumio volumio[1076]: { May 13 20:57:53 volumio volumio[1076]: downloadSpeed: '', May 13 20:57:53 volumio volumio[1076]: eta: '2m', May 13 20:57:53 volumio volumio[1076]: progress: 80, May 13 20:57:53 volumio volumio[1076]: status: 'Downloading new update' May 13 20:57:53 volumio volumio[1076]: } May 13 20:57:53 volumio volumio[1076]: verbose: New Socket.io Connection to 192.168.1.132 from 192.168.1.133 UA: Mozilla/5.0 (Linux; Android 13; SM-S515DL Build/TP1A.220624.014; 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: 6 May 13 20:57:53 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 13 20:57:54 volumio volumio[1076]: info: CoreCommandRouter::volumioGetVisibleSources May 13 20:57:54 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 20:57:54 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState May 13 20:57:54 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 13 20:57:54 volumio volumio[1076]: info: CoreCommandRouter::volumioGetQueue May 13 20:57:54 volumio volumio[1076]: info: CoreStateMachine::getQueue May 13 20:57:54 volumio volumio[1076]: info: CorePlayQueue::getQueue May 13 20:57:54 volumio volumio[1076]: info: Listing playlists May 13 20:57:54 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 13 20:57:54 volumio volumio[1076]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 13 20:57:54 volumio volumio[1076]: info: Received Get System Info May 13 20:57:54 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 20:57:54 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 20:57:54 volumio volumio[1076]: info: Discovery: Getting this device information May 13 20:57:54 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState May 13 20:57:54 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 20:57:54 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState May 13 20:57:54 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 13 20:57:54 volumio volumio[1076]: info: CoreCommandRouter::getUIConfigOnPlugin May 13 20:57:54 volumio volumio[1076]: info: CoreCommandRouter::getUIConfigOnPlugin May 13 20:57:54 volumio volumio[1076]: info: CoreCommandRouter::getUIConfigOnPlugin May 13 20:57:54 volumio volumio[1076]: info: CoreCommandRouter::getUIConfigOnPlugin May 13 20:57:54 volumio volumio[1076]: info: FusionDsp - Sennheiser hd 600 -1.0 tilt May 13 20:57:54 volumio volumio[1076]: info: FusionDsp - Sennheiser hd 600 -1.0 tilt May 13 20:57:54 volumio volumio[1076]: info: FusionDsp - Sennheiser hd 600 -1.0 tilt May 13 20:57:54 volumio volumio[1076]: info: FusionDsp - Sennheiser hd 600 -1.0 tilt May 13 20:57:55 volumio volumio[1076]: info: FusionDsp - .json,Hifiman he 400se stealth.json,Sennheiser hd 600 -1.0 tilt.json,Tilt 3 basic -3.0.json,Tilt 6 basic -6.0.json,sennheiser hd 600.json May 13 20:57:55 volumio volumio[1076]: info: FusionDsp - .json,Hifiman he 400se stealth.json,Sennheiser hd 600 -1.0 tilt.json,Tilt 3 basic -3.0.json,Tilt 6 basic -6.0.json,sennheiser hd 600.json May 13 20:57:55 volumio volumio[1076]: info: FusionDsp - .json,Hifiman he 400se stealth.json,Sennheiser hd 600 -1.0 tilt.json,Tilt 3 basic -3.0.json,Tilt 6 basic -6.0.json,sennheiser hd 600.json May 13 20:57:55 volumio volumio[1076]: info: FusionDsp - .json,Hifiman he 400se stealth.json,Sennheiser hd 600 -1.0 tilt.json,Tilt 3 basic -3.0.json,Tilt 6 basic -6.0.json,sennheiser hd 600.json May 13 20:57:56 volumio wpa_supplicant[919]: TDLS: Creating peer entry for 5a:8a:ca:9d:72:da May 13 20:57:56 volumio wpa_supplicant[919]: TDLS: Dialog Token in TPK M1 21 May 13 20:57:56 volumio wpa_supplicant[919]: nl80211: kernel reports: key addition failed May 13 20:57:56 volumio wpa_supplicant[919]: TDLS: Failed to set TPK to the driver May 13 20:58:01 volumio volumio[1076]: info: CoreCommandRouter::Close All Modals sent May 13 20:58:06 volumio volumio[1076]: info: CoreCommandRouter::Close All Modals sent May 13 20:58:10 volumio volumio[1076]: info: CoreCommandRouter::getUIConfigOnPlugin May 13 20:58:10 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 13 20:58:12 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 13 20:58:12 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 13 20:58:12 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs May 13 20:58:13 volumio volumio[1076]: info: Received Get System Version May 13 20:58:13 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 13 20:58:18 volumio volumio-remote-updater[615]: zsync done May 13 20:58:19 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 20:58:19 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 20:58:19 volumio volumio[1076]: info: Discovery: Getting this device information May 13 20:58:19 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState May 13 20:58:19 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 20:58:20 volumio volumio-remote-updater[615]: PROGRESS: 90, STATUS: "Cleaning old files", ETA: "1m" May 13 20:58:20 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 13 20:58:20 volumio volumio[1076]: updateProgress May 13 20:58:20 volumio volumio[1076]: { May 13 20:58:20 volumio volumio[1076]: downloadSpeed: '', May 13 20:58:20 volumio volumio[1076]: eta: '1m', May 13 20:58:20 volumio volumio[1076]: progress: 90, May 13 20:58:20 volumio volumio[1076]: status: 'Cleaning old files' May 13 20:58:20 volumio volumio[1076]: } May 13 20:58:20 volumio volumio-remote-updater[615]: /bin/cp: cannot stat '/data/volumio_factory.sqsh': No such file or directory May 13 20:58:20 volumio volumio-remote-updater[615]: /bin/rm: cannot remove '/data/volumio_factory.sqsh': No such file or directory May 13 20:58:20 volumio volumio-remote-updater[615]: PROGRESS: 95, STATUS: "Finalizing update", ETA: "30s" May 13 20:58:20 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 13 20:58:20 volumio volumio[1076]: updateProgress May 13 20:58:20 volumio volumio[1076]: { May 13 20:58:20 volumio volumio[1076]: downloadSpeed: '', May 13 20:58:20 volumio volumio[1076]: eta: '30s', May 13 20:58:20 volumio volumio[1076]: progress: 95, May 13 20:58:20 volumio volumio[1076]: status: 'Finalizing update' May 13 20:58:20 volumio volumio[1076]: } May 13 20:58:20 volumio volumio-remote-updater[615]: PROGRESS: 100, STATUS: "success", MESSAGE: "Successfully updated to \"3.807\" version. System restart required." May 13 20:58:20 volumio volumio-remote-updater[615]: No test mode May 13 20:58:20 volumio volumio-remote-updater[615]: No alpha test mode May 13 20:58:21 volumio volumio-remote-updater[615]: [2025-05-13 20:58:21] [disconnect] Disconnect close local:[1000] remote:[1000] May 13 20:58:21 volumio volumio-remote-updater[615]: [2025-05-13 20:58:21] [info] asio async_write error: system:32 (Broken pipe) May 13 20:58:21 volumio volumio-remote-updater[615]: [2025-05-13 20:58:21] [fatal] handle_write_frame error: websocketpp.transport:2 (Underlying Transport Error) May 13 20:58:21 volumio systemd[1]: volumio-remote-updater.service: Succeeded. May 13 20:58:21 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 13 20:58:21 volumio volumio[1076]: updateReady May 13 20:58:21 volumio volumio[1076]: { May 13 20:58:21 volumio volumio[1076]: changeLogLink: 'http://volumio.org', May 13 20:58:21 volumio volumio[1076]: description: '

NEW ADDITIONS

 

', May 13 20:58:21 volumio volumio[1076]: title: 'Update v3.807', May 13 20:58:21 volumio volumio[1076]: updateavailable: true May 13 20:58:21 volumio volumio[1076]: } May 13 20:58:21 volumio systemd[1]: volumio-remote-updater.service: Service RestartSec=100ms expired, scheduling restart. May 13 20:58:21 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 1. May 13 20:58:21 volumio systemd[1]: Stopped volumio-remote-updater.service. May 13 20:58:21 volumio systemd[1]: Started volumio-remote-updater.service. May 13 20:58:21 volumio volumio-remote-updater[2888]: Error: No active session May 13 20:58:21 volumio volumio-remote-updater[2888]: [2025-05-13 20:58:21] [connect] Successful connection May 13 20:58:21 volumio volumio-remote-updater[2888]: [2025-05-13 20:58:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747184301 101 May 13 20:58:21 volumio volumio[1076]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 7 May 13 20:58:21 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 13 20:58:22 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 13 20:58:23 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 13 20:58:24 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 13 20:58:25 volumio volumio[1076]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ May 13 20:58:25 volumio volumio[1076]: info: PLUGIN onReboot : networkfs May 13 20:58:25 volumio volumio[1076]: info: PLUGIN onReboot : audiophonicsonoff May 13 20:58:25 volumio volumio[1076]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 20:58:25 volumio volumio[1076]: TypeError: Cannot read property 'writeSync' of undefined May 13 20:58:25 volumio volumio[1076]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) May 13 20:58:25 volumio volumio[1076]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) May 13 20:58:25 volumio volumio[1076]: at HashMap. (/volumio/app/pluginmanager.js:668:31) May 13 20:58:25 volumio volumio[1076]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 13 20:58:25 volumio volumio[1076]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 13 20:58:25 volumio volumio[1076]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) May 13 20:58:25 volumio volumio[1076]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) May 13 20:58:25 volumio volumio[1076]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:870:33) May 13 20:58:25 volumio volumio[1076]: at Socket.emit (events.js:315:20) May 13 20:58:25 volumio volumio[1076]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 13 20:58:25 volumio volumio[1076]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 13 20:58:25 volumio volumio[1076]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 20:58:26 volumio sudo[2941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-13 20:57 May 13 20:58:26 volumio sudo[2941]: 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="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"