-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Fri 2024-10-11 19:49:08 CST. -- Oct 11 19:48:07 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 19:48:07 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 19:48:07 volumio-pi volumio[965]: info: Discovery: Getting this device information Oct 11 19:48:07 volumio-pi volumio[965]: info: CoreCommandRouter::volumioGetState Oct 11 19:48:07 volumio-pi volumio[965]: info: CorePlayQueue::getTrack 1 Oct 11 19:48:07 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 19:48:22 volumio-pi volumio[965]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.125 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Oct 11 19:48:22 volumio-pi volumio[965]: info: Received Get System Info Oct 11 19:48:22 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 19:48:22 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 19:48:22 volumio-pi volumio[965]: info: Discovery: Getting this device information Oct 11 19:48:22 volumio-pi volumio[965]: info: CoreCommandRouter::volumioGetState Oct 11 19:48:22 volumio-pi volumio[965]: info: CorePlayQueue::getTrack 1 Oct 11 19:48:22 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 19:48:22 volumio-pi volumio[965]: info: CoreCommandRouter::volumioGetState Oct 11 19:48:22 volumio-pi volumio[965]: info: CorePlayQueue::getTrack 1 Oct 11 19:48:22 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 19:48:22 volumio-pi volumio[965]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 19:48:22 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 19:48:22 volumio-pi volumio[965]: info: CoreCommandRouter::volumioGetState Oct 11 19:48:22 volumio-pi volumio[965]: info: CorePlayQueue::getTrack 1 Oct 11 19:48:22 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 19:48:22 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 19:48:22 volumio-pi volumio[965]: info: Received Get System Info Oct 11 19:48:22 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 19:48:22 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 19:48:22 volumio-pi volumio[965]: info: Discovery: Getting this device information Oct 11 19:48:22 volumio-pi volumio[965]: info: CoreCommandRouter::volumioGetState Oct 11 19:48:22 volumio-pi volumio[965]: info: CorePlayQueue::getTrack 1 Oct 11 19:48:22 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 19:48:22 volumio-pi volumio[965]: info: CoreCommandRouter::volumioGetState Oct 11 19:48:22 volumio-pi volumio[965]: info: CorePlayQueue::getTrack 1 Oct 11 19:48:22 volumio-pi volumio[965]: info: Listing playlists Oct 11 19:48:22 volumio-pi volumio[965]: info: CoreCommandRouter::volumioGetQueue Oct 11 19:48:22 volumio-pi volumio[965]: info: CoreStateMachine::getQueue Oct 11 19:48:22 volumio-pi volumio[965]: info: CorePlayQueue::getQueue Oct 11 19:48:22 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 11 19:48:30 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 19:48:30 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 11 19:48:31 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 19:48:37 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 19:48:37 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 19:48:37 volumio-pi volumio[965]: info: Discovery: Getting this device information Oct 11 19:48:37 volumio-pi volumio[965]: info: CoreCommandRouter::volumioGetState Oct 11 19:48:37 volumio-pi volumio[965]: info: CorePlayQueue::getTrack 1 Oct 11 19:48:37 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 19:48:40 volumio-pi volumio[965]: info: Enabling plugin audiophonicsonoff Oct 11 19:48:40 volumio-pi volumio[965]: info: Loading plugin "audiophonicsonoff"... Oct 11 19:48:40 volumio-pi volumio[965]: info: Applying required configuration parameters for plugin audiophonicsonoff Oct 11 19:48:40 volumio-pi volumio[965]: info: Audiophonics on/off initiated Oct 11 19:48:40 volumio-pi volumio[965]: info: PLUGIN START: audiophonicsonoff Oct 11 19:48:40 volumio-pi volumio[965]: info: Configuring GPIO pins Oct 11 19:48:40 volumio-pi volumio[965]: info: Error: Error: EBUSY: resource busy or locked, write Oct 11 19:48:44 volumio-pi sudo[1814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop evo_oled2.service Oct 11 19:48:44 volumio-pi sudo[1814]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 19:48:44 volumio-pi systemd[1]: Stopping OLED Display Service for EVO SABRE... Oct 11 19:48:44 volumio-pi sudo[1232]: pam_unix(sudo:session): session closed for user root Oct 11 19:48:44 volumio-pi systemd[1]: evo_oled2.service: Succeeded. Oct 11 19:48:44 volumio-pi systemd[1]: Stopped OLED Display Service for EVO SABRE. Oct 11 19:48:44 volumio-pi sudo[1814]: pam_unix(sudo:session): session closed for user root Oct 11 19:48:44 volumio-pi volumio[965]: info: EVO SABRE : systemd cmd stop evo_oled2.service : success Oct 11 19:48:44 volumio-pi sudo[1817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop lircd.service Oct 11 19:48:44 volumio-pi sudo[1817]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 19:48:44 volumio-pi sudo[1817]: pam_unix(sudo:session): session closed for user root Oct 11 19:48:44 volumio-pi volumio[965]: error: EVO SABRE : systemd failed cmd stop lircd.service : Error: Command failed: /usr/bin/sudo /bin/systemctl stop lircd.service Oct 11 19:48:44 volumio-pi volumio[965]: Failed to stop lircd.service: Unit lircd.service not loaded. Oct 11 19:48:44 volumio-pi volumio[965]: info: Error: undefined Oct 11 19:48:48 volumio-pi volumio[965]: info: GPIO-Buttons stopped Oct 11 19:48:48 volumio-pi volumio[965]: info: Disabling plugin gpio-buttons Oct 11 19:48:48 volumio-pi volumio[965]: info: Done. Oct 11 19:48:50 volumio-pi volumio[965]: info: Disabling plugin gpio_control Oct 11 19:48:50 volumio-pi volumio[965]: info: Done. Oct 11 19:48:52 volumio-pi sudo[1820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd_oled_plugin.service Oct 11 19:48:52 volumio-pi sudo[1820]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 19:48:52 volumio-pi volumio[965]: info: Disabling plugin mpd_oled Oct 11 19:48:52 volumio-pi volumio[965]: info: Preparing to generate the ALSA configuration file Oct 11 19:48:52 volumio-pi volumio[965]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 11 19:48:52 volumio-pi volumio[965]: info: Reading ALSA contributions from plugins. Oct 11 19:48:52 volumio-pi sudo[1820]: pam_unix(sudo:session): session closed for user root Oct 11 19:48:52 volumio-pi volumio[965]: info: Asound.conf file written Oct 11 19:48:52 volumio-pi sudo[1825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 11 19:48:52 volumio-pi sudo[1825]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 19:48:52 volumio-pi sudo[1825]: pam_unix(sudo:session): session closed for user root Oct 11 19:48:52 volumio-pi volumio[965]: info: Output device has changed, restarting MPD Oct 11 19:48:52 volumio-pi sudo[1830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 19:48:52 volumio-pi sudo[1830]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 19:48:52 volumio-pi volumio[965]: info: Output device has changed, restarting Shairport Sync Oct 11 19:48:52 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 19:48:52 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 19:48:52 volumio-pi sudo[1830]: pam_unix(sudo:session): session closed for user root Oct 11 19:48:52 volumio-pi sudo[1833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 19:48:52 volumio-pi sudo[1833]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 19:48:52 volumio-pi systemd[1]: Stopping Music Player Daemon... Oct 11 19:48:52 volumio-pi volumio[965]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 19:48:52 volumio-pi volumio[965]: info: Done. Oct 11 19:48:52 volumio-pi systemd[1]: mpd.service: Succeeded. Oct 11 19:48:52 volumio-pi systemd[1]: Stopped Music Player Daemon. Oct 11 19:48:52 volumio-pi volumio[965]: info: MPD Permissions set Oct 11 19:48:52 volumio-pi volumio[965]: info: [MPD_OLED Plugin] Stopped mpd_oled_plugin service Oct 11 19:48:52 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 19:48:52 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 19:48:52 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 19:48:52 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 19:48:52 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 19:48:52 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 19:48:52 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 19:48:52 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 19:48:52 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 19:48:52 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 19:48:52 volumio-pi volumio[965]: info: Starting Shairport Sync Oct 11 19:48:52 volumio-pi systemd[1]: Starting Music Player Daemon... Oct 11 19:48:52 volumio-pi sudo[1844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 19:48:52 volumio-pi sudo[1844]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 19:48:52 volumio-pi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 11 19:48:52 volumio-pi systemd[1]: shairport-sync.service: Succeeded. Oct 11 19:48:52 volumio-pi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 11 19:48:52 volumio-pi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 11 19:48:52 volumio-pi sudo[1844]: pam_unix(sudo:session): session closed for user root Oct 11 19:48:52 volumio-pi volumio[965]: info: Shairport-Sync Started Oct 11 19:48:52 volumio-pi sudo[1838]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 11 19:48:52 volumio-pi sudo[1838]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 19:48:52 volumio-pi sudo[1838]: pam_unix(sudo:session): session closed for user root Oct 11 19:48:53 volumio-pi mpd[1848]: Oct 11 19:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 11 19:48:53 volumio-pi systemd[1]: Started Music Player Daemon. Oct 11 19:48:53 volumio-pi sudo[1833]: pam_unix(sudo:session): session closed for user root Oct 11 19:48:53 volumio-pi volumio[965]: error: updateQueue error: null Oct 11 19:49:07 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 19:49:07 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 19:49:07 volumio-pi volumio[965]: info: Discovery: Getting this device information Oct 11 19:49:07 volumio-pi volumio[965]: info: CoreCommandRouter::volumioGetState Oct 11 19:49:07 volumio-pi volumio[965]: info: CorePlayQueue::getTrack 1 Oct 11 19:49:07 volumio-pi volumio[965]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 19:49:08 volumio-pi volumio[965]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Oct 11 19:49:08 volumio-pi volumio[965]: info: PLUGIN onReboot : networkfs Oct 11 19:49:08 volumio-pi sudo[1893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/音乐 Oct 11 19:49:08 volumio-pi sudo[1893]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 19:49:08 volumio-pi systemd[1]: mnt-NAS-\xe9\x9f\xb3\xe4\xb9\x90.mount: Succeeded. Oct 11 19:49:08 volumio-pi sudo[1893]: pam_unix(sudo:session): session closed for user root Oct 11 19:49:08 volumio-pi volumio[965]: info: PLUGIN onReboot : audiophonicsonoff Oct 11 19:49:08 volumio-pi volumio[965]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 19:49:08 volumio-pi volumio[965]: TypeError: Cannot read property 'writeSync' of undefined Oct 11 19:49:08 volumio-pi volumio[965]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Oct 11 19:49:08 volumio-pi volumio[965]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Oct 11 19:49:08 volumio-pi volumio[965]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Oct 11 19:49:08 volumio-pi volumio[965]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Oct 11 19:49:08 volumio-pi volumio[965]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Oct 11 19:49:08 volumio-pi volumio[965]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Oct 11 19:49:08 volumio-pi volumio[965]: at CoreCommandRouter.reboot (/volumio/app/index.js:1331:22) Oct 11 19:49:08 volumio-pi volumio[965]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:864:33) Oct 11 19:49:08 volumio-pi volumio[965]: at Socket.emit (events.js:315:20) Oct 11 19:49:08 volumio-pi volumio[965]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 11 19:49:08 volumio-pi volumio[965]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 11 19:49:08 volumio-pi volumio[965]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 19:49:08 volumio-pi sudo[1905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-11 19:48 Oct 11 19:49:08 volumio-pi sudo[1905]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"