-- Logs begin at Thu 2019-02-14 19:11:59 JST, end at Thu 2025-06-12 21:17:13 JST. -- Jun 12 21:16:01 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 12 21:16:01 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 12 21:16:05 volumio volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 21:16:05 volumio volumio[832]: error: [ytmusic] getUIConfig(): Cannot populate YouTube Music configuration: VISITOR_INFO1_LIVE=i6XifW0KiWQ; VISITOR_PRIVACY_METADATA=CgJKUBIEGgAgOQ%3D%3D; PREF=tz=Asia.Tokyo&f5=20000&f7=150&repeat=NONE&autoplay=true&volume=17&guide_collapsed=false&f6=40000000; SID=g.a000xggxi0hJuEMJ2UlyM4ZF0eNqg3dxD_guyB1zpXT_yTreDezyS0PBBiq7Uen0qC8X4a_HBQACgYKAfMSARMSFQHGX2MizBttkRD2cgTcjhr7w4CwhhoVAUF8yKoNQIrwfCGS0Ur6eBnPaWlv0076; __Secure-1PSIDTS=sidts-CjEB5H03PxsWWWfPwj0vvS9NrQccJQpeiX3evR-A5APGlySocd9lIn75Tei_exPjB7q-EAA; __Secure-3PSIDTS=sidts-CjEB5H03PxsWWWfPwj0vvS9NrQccJQpeiX3evR-A5APGlySoc…RHpmc1F4eDJYZXNvb0hMdEhtMnlUNkR4UmFFa3BGdktJQmxvUjQxbWs3NjZKY1I0UDhJNWpRMVZPRXV0eHdReHFyeUthQVFyRzRIXzhheXZOM0dTVUxxS2E4ejNMNFhMY0R2Q1hLTFdESzl1U3FUWjFZZ1RHQUlB; ST-yve142=session_logininfo=AFmmF2swRQIgDzEr81ma6Rvs9GP-CPT886870FGqPyoxAtGw4utefJkCIQDGAp5OPeFYnzww37Hk03FLxFtfHwPs6dMEWrz84kh1jw%3AQUQ3MjNmemN0bFJxdW9xbDF2cFJCUDJPUkkzbHNibXM2S0ZBSnhyRTU1RHpmc1F4eDJYZXNvb0hMdEhtMnlUNkR4UmFFa3BGdktJQmxvUjQxbWs3NjZKY1I0UDhJNWpRMVZPRXV0eHdReHFyeUthQVFyRzRIXzhheXZOM0dTVUxxS2E4ejNMNFhMY0R2Q1hLTFdESzl1U3FUWjFZZ1RHQUlB is not a legal HTTP header value TypeError: VISITOR_INFO1_LIVE=i6XifW0KiWQ; VISITOR_PRIVACY_METADATA=CgJKUBIEGgAgOQ%3D%3D; PREF=tz=Asia.Tokyo&f5=20000&f7=150&repeat=NONE&autoplay=true&volume=17&guide_collapsed=false&f6=40000000; SID=g.a000xggxi0hJuEMJ2UlyM4ZF0eNqg3dxD_guyB1zpXT_yTreDezyS0PBBiq7Uen0qC8X4a_HBQACgYKAfMSARMSFQHGX2MizBttkRD2cgTcjhr7w4CwhhoVAUF8yKoNQIrwfCGS0Ur6eBnPaWlv0076; __Secure-1PSIDTS=sidts-CjEB5H03PxsWWWfPwj0vvS9NrQccJQpeiX3evR-A5APGlySocd9lIn75Tei_exPjB7q-EAA; __Secure-3PSIDTS=sidts-CjEB5H03PxsWWWfPwj0vvS9NrQccJQpeiX3evR-A5APGlySoc…RHpmc1F4eDJYZXNvb0hMdEhtMnlUNkR4UmFFa3BGdktJQmxvUjQxbWs3NjZKY1I0UDhJNWpRMVZPRXV0eHdReHFyeUthQVFyRzRIXzhheXZOM0dTVUxxS2E4ejNMNFhMY0R2Q1hLTFdESzl1U3FUWjFZZ1RHQUlB; ST-yve142=session_logininfo=AFmmF2swRQIgDzEr81ma6Rvs9GP-CPT886870FGqPyoxAtGw4utefJkCIQDGAp5OPeFYnzww37Hk03FLxFtfHwPs6dMEWrz84kh1jw%3AQUQ3MjNmemN0bFJxdW9xbDF2cFJCUDJPUkkzbHNibXM2S0ZBSnhyRTU1RHpmc1F4eDJYZXNvb0hMdEhtMnlUNkR4UmFFa3BGdktJQmxvUjQxbWs3NjZKY1I0UDhJNWpRMVZPRXV0eHdReHFyeUthQVFyRzRIXzhheXZOM0dTVUxxS2E4ejNMNFhMY0R2Q1hLTFdESzl1U3FUWjFZZ1RHQUlB is not a legal HTTP header value Jun 12 21:16:05 volumio volumio[832]: at validateValue (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:2329:15) Jun 12 21:16:05 volumio volumio[832]: at _Headers.set (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:2439:9) Jun 12 21:16:05 volumio volumio[832]: at _HTTPClient.fetch (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:8515:25) Jun 12 21:16:05 volumio volumio[832]: at _Actions.execute (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:34148:46) Jun 12 21:16:05 volumio volumio[832]: at _NavigationEndpoint.call (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:11499:22) Jun 12 21:16:05 volumio volumio[832]: at _AccountManager.getInfo (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:39378:59) Jun 12 21:16:05 volumio volumio[832]: at getAccountInitialInfo (/data/plugins/music_service/ytmusic/dist/lib/model/AccountModelHelper.js:48:46) Jun 12 21:16:05 volumio volumio[832]: at Function._InnertubeLoader_recreateWithPOToken (/data/plugins/music_service/ytmusic/dist/lib/model/InnertubeLoader.js:87:78) Jun 12 21:16:05 volumio volumio[832]: at Function._InnertubeLoader_createInstance (/data/plugins/music_service/ytmusic/dist/lib/model/InnertubeLoader.js:162:95) Jun 12 21:16:05 volumio volumio[832]: at runMicrotasks () Jun 12 21:16:05 volumio volumio[832]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jun 12 21:16:29 volumio volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 21:16:29 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 12 21:16:29 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jun 12 21:16:29 volumio sudo[4440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jun 12 21:16:29 volumio sudo[4440]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 21:16:29 volumio sudo[4445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 12 21:16:29 volumio sudo[4445]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 21:16:29 volumio sudo[4445]: pam_unix(sudo:session): session closed for user root Jun 12 21:16:29 volumio sudo[4455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 12 21:16:29 volumio sudo[4455]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 21:16:29 volumio sudo[4455]: pam_unix(sudo:session): session closed for user root Jun 12 21:16:29 volumio sudo[4462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 12 21:16:29 volumio sudo[4462]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 21:16:29 volumio sudo[4462]: pam_unix(sudo:session): session closed for user root Jun 12 21:16:29 volumio sudo[4473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 12 21:16:29 volumio sudo[4473]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 21:16:29 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 12 21:16:29 volumio sudo[4440]: pam_unix(sudo:session): session closed for user root Jun 12 21:16:29 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 12 21:16:29 volumio sudo[4478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 12 21:16:29 volumio sudo[4478]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 21:16:29 volumio sudo[4473]: pam_unix(sudo:session): session closed for user root Jun 12 21:16:29 volumio sudo[4478]: pam_unix(sudo:session): session closed for user root Jun 12 21:16:29 volumio sudo[4484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 12 21:16:29 volumio sudo[4484]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 21:16:30 volumio sudo[4484]: pam_unix(sudo:session): session closed for user root Jun 12 21:16:37 volumio volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 21:16:37 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 12 21:16:37 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 21:16:37 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 12 21:16:37 volumio volumio[832]: info: Getting Alsa Cards List without I2S DAC Jun 12 21:16:38 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jun 12 21:16:38 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 21:16:38 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 21:16:38 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 21:16:38 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 21:16:38 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 21:16:38 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 21:16:38 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 21:16:38 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 21:16:38 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 12 21:16:38 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 12 21:16:42 volumio volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 21:16:42 volumio volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 21:16:42 volumio volumio[832]: info: Retrieving Cloud Streaming UI Jun 12 21:16:42 volumio volumio[832]: info: Getting Tidal Cloud Configuration Jun 12 21:16:42 volumio volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 21:16:42 volumio volumio[832]: info: Getting Qobuz Cloud Configuration Jun 12 21:16:42 volumio volumio[832]: info: Asking plugin for UI Config Jun 12 21:16:42 volumio volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 21:16:42 volumio volumio[832]: info: Getting Spotify Cloud Configuration Jun 12 21:16:42 volumio volumio[832]: info: Asking plugin for UI Config Jun 12 21:16:42 volumio volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 21:16:42 volumio volumio[832]: info: Saving Spotify Acccount Jun 12 21:16:42 volumio volumio[832]: info: Got Tidal Cloud Configuration Jun 12 21:16:42 volumio volumio[832]: info: Got it Jun 12 21:16:42 volumio volumio[832]: info: Got it Jun 12 21:16:42 volumio volumio[832]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 12 21:16:42 volumio volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 21:16:42 volumio volumio[832]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jun 12 21:16:42 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 12 21:16:42 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 12 21:16:42 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 12 21:16:42 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 12 21:16:42 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 21:16:42 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 21:16:42 volumio volumio[832]: info: CoreCommandRouter::volumioGetBrowseSources Jun 12 21:16:42 volumio volumio[832]: info: CoreCommandRouter::volumioGetBrowseSources Jun 12 21:16:42 volumio volumio[832]: info: CoreCommandRouter::volumioGetBrowseSources Jun 12 21:16:43 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 21:16:43 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 12 21:16:47 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 21:16:50 volumio volumio[832]: info: Enabling MyMusic plugin bluetooth Jun 12 21:16:50 volumio volumio[832]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Jun 12 21:16:50 volumio volumio[832]: info: [MyVolumio PluginManager] Plugin audio_interface bluetooth not in user plan, not enabling Jun 12 21:16:50 volumio volumio[832]: info: Error: Plugin audio_interface bluetooth not in user plan, not enabling Jun 12 21:16:50 volumio volumio[832]: error: Could not Enable MyMusic Plugin: Error Jun 12 21:16:51 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 21:16:55 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 21:16:59 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 21:16:59 volumio volumio[832]: info: Disabling MyMusic plugin upnp_browser Jun 12 21:16:59 volumio volumio[832]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesDLNAサーバー Jun 12 21:16:59 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 12 21:16:59 volumio volumio[832]: Cannot find translation for source YouTube Music Jun 12 21:16:59 volumio volumio[832]: info: Disabling plugin upnp_browser Jun 12 21:16:59 volumio volumio[832]: info: Done. Jun 12 21:17:01 volumio volumio[832]: info: Disabling MyMusic plugin upnp Jun 12 21:17:01 volumio sudo[4545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jun 12 21:17:01 volumio sudo[4545]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 21:17:01 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Jun 12 21:17:01 volumio volumio[832]: error: Upnp client error: Error: This socket has been ended by the other party Jun 12 21:17:03 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 21:17:07 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 21:17:11 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 21:17:12 volumio volumio[832]: info: Enabling MyMusic plugin upnp Jun 12 21:17:12 volumio volumio[832]: info: Enabling plugin upnp Jun 12 21:17:12 volumio volumio[832]: info: Loading plugin "upnp"... Jun 12 21:17:12 volumio volumio[832]: info: [1749730632735] Starting Upmpd Daemon Jun 12 21:17:12 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 12 21:17:12 volumio volumio[832]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 21:17:12 volumio volumio[832]: Error: listen EADDRINUSE: address already in use :::6599 Jun 12 21:17:12 volumio volumio[832]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Jun 12 21:17:12 volumio volumio[832]: at listenInCluster (net.js:1366:12) Jun 12 21:17:12 volumio volumio[832]: at Server.listen (net.js:1452:7) Jun 12 21:17:12 volumio volumio[832]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jun 12 21:17:12 volumio volumio[832]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jun 12 21:17:12 volumio volumio[832]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jun 12 21:17:12 volumio volumio[832]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 12 21:17:12 volumio volumio[832]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Jun 12 21:17:12 volumio volumio[832]: code: 'EADDRINUSE', Jun 12 21:17:12 volumio volumio[832]: errno: -98, Jun 12 21:17:12 volumio volumio[832]: syscall: 'listen', Jun 12 21:17:12 volumio volumio[832]: address: '::', Jun 12 21:17:12 volumio volumio[832]: port: 6599 Jun 12 21:17:12 volumio volumio[832]: } Jun 12 21:17:12 volumio volumio[832]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 21:17:13 volumio sudo[4574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-12 21:16 Jun 12 21:17:13 volumio sudo[4574]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"