-- Logs begin at Wed 2024-09-04 18:00:59 UTC, end at Wed 2024-09-04 18:09:47 UTC. -- Sep 04 18:08:00 volumio volumio[974]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 04 18:08:00 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 04 18:08:00 volumio volumio[974]: info: CoreCommandRouter::volumioGetState Sep 04 18:08:00 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Sep 04 18:08:00 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 04 18:08:00 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 04 18:08:00 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 04 18:08:00 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 04 18:08:02 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Sep 04 18:08:02 volumio sudo[1640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Sep 04 18:08:02 volumio sudo[1640]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 18:08:02 volumio sudo[1645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 04 18:08:02 volumio sudo[1645]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 18:08:02 volumio sudo[1640]: pam_unix(sudo:session): session closed for user root Sep 04 18:08:02 volumio sudo[1645]: pam_unix(sudo:session): session closed for user root Sep 04 18:08:02 volumio sudo[1650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 04 18:08:02 volumio sudo[1650]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 18:08:02 volumio sudo[1656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 04 18:08:02 volumio sudo[1656]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 18:08:02 volumio sudo[1650]: pam_unix(sudo:session): session closed for user root Sep 04 18:08:02 volumio sudo[1656]: pam_unix(sudo:session): session closed for user root Sep 04 18:08:02 volumio sudo[1665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 04 18:08:02 volumio sudo[1665]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 18:08:02 volumio sudo[1667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 04 18:08:02 volumio sudo[1667]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 18:08:02 volumio sudo[1665]: pam_unix(sudo:session): session closed for user root Sep 04 18:08:02 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Sep 04 18:08:02 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Sep 04 18:08:02 volumio sudo[1667]: pam_unix(sudo:session): session closed for user root Sep 04 18:08:02 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 04 18:08:02 volumio sudo[1671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 04 18:08:02 volumio sudo[1671]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 18:08:03 volumio sudo[1671]: pam_unix(sudo:session): session closed for user root Sep 04 18:08:04 volumio sudo[1678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 04 18:08:04 volumio sudo[1678]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 18:08:04 volumio sudo[1678]: pam_unix(sudo:session): session closed for user root Sep 04 18:08:05 volumio sudo[1680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 04 18:08:05 volumio sudo[1680]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 18:08:05 volumio sudo[1680]: pam_unix(sudo:session): session closed for user root Sep 04 18:08:05 volumio sudo[1683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 04 18:08:05 volumio sudo[1683]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 18:08:05 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Sep 04 18:08:05 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 04 18:08:05 volumio sudo[1683]: pam_unix(sudo:session): session closed for user root Sep 04 18:08:05 volumio volumio[974]: info: Upmpdcli Daemon Started Sep 04 18:08:05 volumio volumio[974]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Sep 04 18:08:11 volumio volumio[974]: info: CoreCommandRouter::volumioGetVisibleSources Sep 04 18:08:11 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 18:08:11 volumio volumio[974]: info: CoreCommandRouter::volumioGetQueue Sep 04 18:08:11 volumio volumio[974]: info: CoreStateMachine::getQueue Sep 04 18:08:11 volumio volumio[974]: info: CorePlayQueue::getQueue Sep 04 18:08:11 volumio volumio[974]: info: Listing playlists Sep 04 18:08:16 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 04 18:08:16 volumio volumio[974]: info: CURURI: music-library Sep 04 18:08:16 volumio volumio[974]: error: Failed to execute browseSource: TypeError: Cannot read property 'then' of undefined Sep 04 18:08:17 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 04 18:08:17 volumio volumio[974]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 04 18:08:17 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 04 18:08:17 volumio volumio[974]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Sep 04 18:08:17 volumio volumio[974]: info: Received Get System Version Sep 04 18:08:17 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 04 18:08:37 volumio volumio[974]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Sep 04 18:08:58 volumio kernel: perf interrupt took too long (5012 > 5000), lowering kernel.perf_event_max_sample_rate to 25000 Sep 04 18:09:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 18:09:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 18:09:08 volumio volumio[974]: info: Discovery: Getting this device information Sep 04 18:09:08 volumio volumio[974]: info: CoreCommandRouter::volumioGetState Sep 04 18:09:08 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Sep 04 18:09:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Sep 04 18:09:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConf Sep 04 18:09:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConf Sep 04 18:09:08 volumio volumio[974]: info: CoreCommandRouter::volumioGetState Sep 04 18:09:08 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Sep 04 18:09:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 04 18:09:08 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 04 18:09:10 volumio sudo[1738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 04 18:09:10 volumio sudo[1738]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 18:09:10 volumio sudo[1740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 04 18:09:10 volumio sudo[1740]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 18:09:10 volumio sudo[1738]: pam_unix(sudo:session): session closed for user root Sep 04 18:09:10 volumio sudo[1740]: pam_unix(sudo:session): session closed for user root Sep 04 18:09:10 volumio sudo[1744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 04 18:09:10 volumio sudo[1744]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 18:09:10 volumio sudo[1744]: pam_unix(sudo:session): session closed for user root Sep 04 18:09:10 volumio sudo[1746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 04 18:09:10 volumio sudo[1746]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 18:09:10 volumio sudo[1746]: pam_unix(sudo:session): session closed for user root Sep 04 18:09:10 volumio volumio[974]: info: CoreCommandRouter::volumioGetState Sep 04 18:09:10 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Sep 04 18:09:10 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 04 18:09:10 volumio volumio[974]: info: Listing playlists Sep 04 18:09:10 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 04 18:09:10 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 04 18:09:10 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 04 18:09:10 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 04 18:09:11 volumio volumio[974]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Sep 04 18:09:12 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 04 18:09:16 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 18:09:16 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 18:09:16 volumio volumio[974]: info: Discovery: Getting this device information Sep 04 18:09:16 volumio volumio[974]: info: CoreCommandRouter::volumioGetState Sep 04 18:09:16 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Sep 04 18:09:16 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Sep 04 18:09:16 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConf Sep 04 18:09:16 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConf Sep 04 18:09:17 volumio volumio[974]: info: CoreCommandRouter::volumioGetVisibleSources Sep 04 18:09:17 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 18:09:17 volumio volumio[974]: info: CoreCommandRouter::volumioGetQueue Sep 04 18:09:17 volumio volumio[974]: info: CoreStateMachine::getQueue Sep 04 18:09:17 volumio volumio[974]: info: CorePlayQueue::getQueue Sep 04 18:09:22 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 04 18:09:28 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 04 18:09:28 volumio volumio[974]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 04 18:09:28 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 04 18:09:28 volumio volumio[974]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Sep 04 18:09:28 volumio volumio[974]: info: Received Get System Version Sep 04 18:09:28 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 04 18:09:45 volumio volumio[974]: info: CoreCommandRouter::servicePushState Sep 04 18:09:45 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Sep 04 18:09:45 volumio volumio[974]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":0,"duration":0,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true} Sep 04 18:09:45 volumio volumio[974]: verbose: CURRENT POSITION 0 Sep 04 18:09:45 volumio volumio[974]: info: CoreStateMachine::syncState stateService play Sep 04 18:09:45 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus stop Sep 04 18:09:45 volumio volumio[974]: info: CoreStateMachine::pushState Sep 04 18:09:45 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Sep 04 18:09:45 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Sep 04 18:09:45 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 18:09:45 volumio volumio[974]: info: CoreCommandRouter::servicePushState Sep 04 18:09:45 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Sep 04 18:09:45 volumio volumio[974]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":0,"duration":0,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true} Sep 04 18:09:45 volumio volumio[974]: verbose: CURRENT POSITION 0 Sep 04 18:09:45 volumio volumio[974]: info: CoreStateMachine::syncState stateService play Sep 04 18:09:45 volumio volumio[974]: info: CoreStateMachine::syncState currentStatus play Sep 04 18:09:45 volumio volumio[974]: info: Received an update from plugin. extracting info from payload Sep 04 18:09:45 volumio volumio[974]: info: CoreStateMachine::pushState Sep 04 18:09:45 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Sep 04 18:09:45 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Sep 04 18:09:45 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 18:09:45 volumio volumio[974]: info: CoreStateMachine::pushState Sep 04 18:09:45 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Sep 04 18:09:45 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Sep 04 18:09:45 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 18:09:45 volumio shairport-sync[1249]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 04 18:09:45 volumio shairport-sync[1249]: Dload Upload Total Spent Left Speed Sep 04 18:09:46 volumio volumio[974]: info: Sep 04 18:09:46 volumio volumio[974]: ---------------------------- Client requests AirplayActive Sep 04 18:09:46 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive Sep 04 18:09:46 volumio shairport-sync[1249]: [155B blob data] Sep 04 18:09:46 volumio shairport-sync[1249]: {"time":1725473386020,"response":"airplayActive Success"}Failed to create secure directory (/home/shairport-sync/.config/pulse): No such file or directory Sep 04 18:09:46 volumio shairport-sync[1249]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 04 18:09:46 volumio volumio[974]: info: Airplay started streaming, receiving metadatas Sep 04 18:09:46 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Sep 04 18:09:46 volumio volumio[974]: info: CoreCommandRouter::volumioStop Sep 04 18:09:46 volumio volumio[974]: info: CoreStateMachine::stop Sep 04 18:09:46 volumio volumio[974]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 04 18:09:46 volumio volumio[974]: verbose: UNSET VOLATILE Sep 04 18:09:46 volumio volumio[974]: info: CoreStateMachine::stPlaybackTimer Sep 04 18:09:46 volumio volumio[974]: info: CoreStateMachine::updateTrackBlock Sep 04 18:09:46 volumio shairport-sync[1249]: Dload Upload Total Spent Left Speed Sep 04 18:09:46 volumio volumio[974]: info: CorePlayQueue::getTrackBlock Sep 04 18:09:46 volumio volumio[974]: info: CoreStateMachine::pushState Sep 04 18:09:46 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Sep 04 18:09:46 volumio volumio[974]: info: CoreCommandRouter::volumioPushState Sep 04 18:09:46 volumio volumio[974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 18:09:46 volumio volumio[974]: info: CoreStateMachine::serviceStop Sep 04 18:09:46 volumio volumio[974]: info: CorePlayQueue::getTrack 0 Sep 04 18:09:46 volumio volumio[974]: info: CoreCommandRouter::serviceStop Sep 04 18:09:46 volumio volumio[974]: info: [1725473386123] ControllerUPNPBrowser::stop Sep 04 18:09:46 volumio volumio[974]: verbose: ControllerMpd::sendMpdCommand stop Sep 04 18:09:46 volumio volumio[974]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 18:09:46 volumio volumio[974]: TypeError: Cannot read property 'then' of undefined Sep 04 18:09:46 volumio volumio[974]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:330:6) Sep 04 18:09:46 volumio volumio[974]: at ControllerUPNPBrowser.stop (/volumio/app/plugins/music_service/upnp_browser/index.js:433:25) Sep 04 18:09:46 volumio volumio[974]: at CoreCommandRouter.serviceStop (/volumio/app/index.js:357:25) Sep 04 18:09:46 volumio volumio[974]: at CoreStateMachine.serviceStop (/volumio/app/statemachine.js:1238:33) Sep 04 18:09:46 volumio volumio[974]: at CoreStateMachine.stop (/volumio/app/statemachine.js:1213:19) Sep 04 18:09:46 volumio volumio[974]: at CoreCommandRouter.volumioStop (/volumio/app/index.js:81:28) Sep 04 18:09:46 volumio volumio[974]: at AirPlayInterface.prepareAirplayPlayback (/volumio/app/plugins/music_service/airplay_emulation/index.js:517:32) Sep 04 18:09:46 volumio volumio[974]: at ShairportSyncReaderUDP. (/volumio/app/plugins/music_service/airplay_emulation/index.js:231:12) Sep 04 18:09:46 volumio volumio[974]: at emitOne (events.js:121:20) Sep 04 18:09:46 volumio volumio[974]: at ShairportSyncReaderUDP.emit (events.js:211:7) Sep 04 18:09:46 volumio volumio[974]: at ShairportSyncReaderUDP.useData (/volumio/app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-base.js:86:16) Sep 04 18:09:46 volumio volumio[974]: at Socket.ShairportSyncReaderUDP._source.on.msg (/volumio/app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-udp.js:21:12) Sep 04 18:09:46 volumio volumio[974]: at emitTwo (events.js:126:13) Sep 04 18:09:46 volumio volumio[974]: at Socket.emit (events.js:214:7) Sep 04 18:09:46 volumio volumio[974]: at UDP.onMessage [as onmessage] (dgram.js:659:8) Sep 04 18:09:46 volumio volumio[974]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 18:09:47 volumio sudo[1777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-04 18:08 Sep 04 18:09:47 volumio sudo[1777]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 11:49:13 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="62511ec23417b6085328d5bda7373a0c"