-- Logs begin at Tue 2024-05-07 10:09:51 CST, end at Mon 2024-09-09 18:28:13 CST. -- Sep 09 18:27:06 volumio-wld volumio[819]: info: MyVolumio login type: Token Sep 09 18:27:19 volumio-wld sudo[8117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 09 18:27:19 volumio-wld sudo[8117]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 18:27:19 volumio-wld sudo[8117]: pam_unix(sudo:session): session closed for user root Sep 09 18:27:19 volumio-wld sudo[8120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 09 18:27:19 volumio-wld sudo[8120]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 18:27:19 volumio-wld sudo[8120]: pam_unix(sudo:session): session closed for user root Sep 09 18:27:19 volumio-wld volumio[819]: verbose: New Socket.io Connection to 192.168.137.121 from 192.168.137.1 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Total Clients: 35 Sep 09 18:27:19 volumio-wld volumio[819]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 18:27:19 volumio-wld volumio[819]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 09 18:27:20 volumio-wld volumio[819]: info: CoreCommandRouter::volumioGetVisibleSources Sep 09 18:27:20 volumio-wld volumio[819]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 18:27:20 volumio-wld volumio[819]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 09 18:27:20 volumio-wld volumio[819]: info: CoreCommandRouter::volumioGetState Sep 09 18:27:20 volumio-wld volumio[819]: info: CorePlayQueue::getTrack 0 Sep 09 18:27:20 volumio-wld volumio[819]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 09 18:27:20 volumio-wld volumio[819]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 09 18:27:20 volumio-wld volumio[819]: info: Received Get System Info Sep 09 18:27:20 volumio-wld volumio[819]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 18:27:20 volumio-wld volumio[819]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 18:27:20 volumio-wld volumio[819]: info: Discovery: Getting this device information Sep 09 18:27:20 volumio-wld volumio[819]: info: CoreCommandRouter::volumioGetState Sep 09 18:27:20 volumio-wld volumio[819]: info: CorePlayQueue::getTrack 0 Sep 09 18:27:20 volumio-wld volumio[819]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 18:27:20 volumio-wld volumio[819]: info: CoreCommandRouter::volumioGetState Sep 09 18:27:20 volumio-wld volumio[819]: info: CorePlayQueue::getTrack 0 Sep 09 18:27:20 volumio-wld volumio[819]: info: Listing playlists Sep 09 18:27:20 volumio-wld volumio[819]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 09 18:27:20 volumio-wld volumio[819]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 09 18:27:20 volumio-wld volumio[819]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 09 18:27:20 volumio-wld volumio[819]: info: CorePlayQueue::getTrack 0 Sep 09 18:27:20 volumio-wld volumio[819]: info: CorePlayQueue::getTrack 1 Sep 09 18:27:20 volumio-wld volumio[819]: info: Prefetching next song Sep 09 18:27:20 volumio-wld volumio[819]: info: DOING PREFETCH IN MPD Sep 09 18:27:20 volumio-wld volumio[819]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/music/邓丽君/02.小城故事.wav" Sep 09 18:27:20 volumio-wld volumio[819]: info: Sep 09 18:27:20 volumio-wld volumio[819]: ---------------------------- MPD announces system playlist update Sep 09 18:27:20 volumio-wld volumio[819]: info: Ignoring MPD Status Update Sep 09 18:27:20 volumio-wld volumio[819]: info: sendMpdCommand add "INTERNAL/music/邓丽君/02.小城故事.wav" took 4 milliseconds Sep 09 18:27:20 volumio-wld volumio[819]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 09 18:27:20 volumio-wld volumio[819]: info: Sep 09 18:27:20 volumio-wld volumio[819]: ---------------------------- MPD announces system playlist update Sep 09 18:27:20 volumio-wld volumio[819]: info: Ignoring MPD Status Update Sep 09 18:27:20 volumio-wld volumio[819]: info: Sep 09 18:27:20 volumio-wld volumio[819]: ---------------------------- MPD announces system playlist update Sep 09 18:27:20 volumio-wld volumio[819]: info: Ignoring MPD Status Update Sep 09 18:27:20 volumio-wld volumio[819]: info: Sep 09 18:27:20 volumio-wld volumio[819]: ---------------------------- MPD announces system playlist update Sep 09 18:27:20 volumio-wld volumio[819]: info: Ignoring MPD Status Update Sep 09 18:27:20 volumio-wld volumio[819]: info: ------------------------------ 16ms Sep 09 18:27:20 volumio-wld volumio[819]: info: sendMpdCommand consume 1 took 15 milliseconds Sep 09 18:27:20 volumio-wld volumio[819]: info: ------------------------------ 12ms Sep 09 18:27:20 volumio-wld volumio[819]: info: ------------------------------ 10ms Sep 09 18:27:20 volumio-wld volumio[819]: info: ------------------------------ 7ms Sep 09 18:27:21 volumio-wld volumio[819]: info: CoreCommandRouter::Close All Modals sent Sep 09 18:27:21 volumio-wld volumio[819]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 09 18:27:21 volumio-wld volumio[819]: info: Received Get System Info Sep 09 18:27:21 volumio-wld volumio[819]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 18:27:21 volumio-wld volumio[819]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 18:27:21 volumio-wld volumio[819]: info: Discovery: Getting this device information Sep 09 18:27:21 volumio-wld volumio[819]: info: CoreCommandRouter::volumioGetState Sep 09 18:27:21 volumio-wld volumio[819]: info: CorePlayQueue::getTrack 0 Sep 09 18:27:21 volumio-wld volumio[819]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 18:27:23 volumio-wld volumio[819]: info: Enabling plugin ir_controller Sep 09 18:27:23 volumio-wld volumio[819]: info: Loading plugin "ir_controller"... Sep 09 18:27:23 volumio-wld volumio[819]: info: PLUGIN START: ir_controller Sep 09 18:27:23 volumio-wld volumio[819]: info: Loading i18n strings for locale zh Sep 09 18:27:23 volumio-wld volumio[819]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Sep 09 18:27:23 volumio-wld volumio[819]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 09 18:27:23 volumio-wld sudo[8139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Sep 09 18:27:23 volumio-wld sudo[8139]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 18:27:23 volumio-wld sudo[8139]: pam_unix(sudo:session): session closed for user root Sep 09 18:27:23 volumio-wld volumio[819]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Sep 09 18:27:23 volumio-wld volumio[819]: info: ir_controller: LIRC correctly updated. Sep 09 18:27:23 volumio-wld volumio[819]: info: ir_controller: HAT did not load /proc/device-tree/ir_receiver! Sep 09 18:27:23 volumio-wld volumio[819]: info: Done. Sep 09 18:27:24 volumio-wld volumio[819]: info: ir_controller: Raspberry Pi revision code: b03140 Sep 09 18:27:24 volumio-wld volumio[819]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 18:27:24 volumio-wld sudo[8150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r -1 Sep 09 18:27:24 volumio-wld sudo[8150]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 18:27:24 volumio-wld sudo[8150]: pam_unix(sudo:session): session closed for user root Sep 09 18:27:24 volumio-wld sudo[8157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-ir gpio_pin=25 gpio_pull=up Sep 09 18:27:24 volumio-wld sudo[8157]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 18:27:24 volumio-wld sudo[8157]: pam_unix(sudo:session): session closed for user root Sep 09 18:27:24 volumio-wld volumio[819]: info: ir_controller: Overlay gpio-ir gpio_pin=25 gpio_pull=up loaded. Sep 09 18:27:24 volumio-wld kernel: Registered IR keymap rc-rc6-mce Sep 09 18:27:24 volumio-wld sudo[8169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop lircd.service Sep 09 18:27:24 volumio-wld sudo[8169]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 18:27:24 volumio-wld kernel: IR RC6 protocol handler initialized Sep 09 18:27:24 volumio-wld kernel: rc rc0: gpio_ir_recv as /devices/platform/ir-receiver@19/rc/rc0 Sep 09 18:27:24 volumio-wld kernel: rc rc0: lirc_dev: driver gpio_ir_recv registered at minor = 0, raw IR receiver, no transmitter Sep 09 18:27:24 volumio-wld kernel: input: gpio_ir_recv as /devices/platform/ir-receiver@19/rc/rc0/input0 Sep 09 18:27:24 volumio-wld sudo[8169]: pam_unix(sudo:session): session closed for user root Sep 09 18:27:24 volumio-wld volumio[819]: info: ir_controller: systemctl stop lircd.service succeeded. Sep 09 18:27:24 volumio-wld sudo[8176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start lircd.service Sep 09 18:27:24 volumio-wld sudo[8176]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 18:27:24 volumio-wld systemd[1]: Starting lircd(8) initialization helper tool... Sep 09 18:27:25 volumio-wld sudo[8184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop lircd.service Sep 09 18:27:25 volumio-wld sudo[8184]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 18:27:25 volumio-wld systemd-udevd[8163]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 09 18:27:25 volumio-wld systemd-logind[500]: Watching system buttons on /dev/input/event0 (gpio_ir_recv) Sep 09 18:27:25 volumio-wld sudo[8176]: pam_unix(sudo:session): session closed for user root Sep 09 18:27:25 volumio-wld volumio[819]: error: ir_controller: Failed to start lircd.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start lircd.service Sep 09 18:27:25 volumio-wld volumio[819]: Job for lircd.service canceled. Sep 09 18:27:25 volumio-wld sudo[8184]: pam_unix(sudo:session): session closed for user root Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreStateMachine::startPlaybackTimer Sep 09 18:27:25 volumio-wld volumio[819]: info: CorePlayQueue::getTrack 1 Sep 09 18:27:25 volumio-wld volumio[819]: info: ir_controller: systemctl stop lircd.service succeeded. Sep 09 18:27:25 volumio-wld sudo[8187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start lircd.service Sep 09 18:27:25 volumio-wld sudo[8187]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 18:27:25 volumio-wld lircd-setup[8179]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Sep 09 18:27:25 volumio-wld lircd-setup[8179]: parser = configparser.SafeConfigParser() Sep 09 18:27:25 volumio-wld systemd[1]: lircd-setup.service: Succeeded. Sep 09 18:27:25 volumio-wld systemd[1]: Started lircd(8) initialization helper tool. Sep 09 18:27:25 volumio-wld volumio[819]: info: Sep 09 18:27:25 volumio-wld volumio[819]: ---------------------------- MPD announces system playlist update Sep 09 18:27:25 volumio-wld volumio[819]: info: Ignoring MPD Status Update Sep 09 18:27:25 volumio-wld volumio[819]: info: Sep 09 18:27:25 volumio-wld volumio[819]: ---------------------------- MPD announces state update: player Sep 09 18:27:25 volumio-wld volumio[819]: info: ControllerMpd::getState Sep 09 18:27:25 volumio-wld volumio[819]: verbose: ControllerMpd::sendMpdCommand status Sep 09 18:27:25 volumio-wld volumio[819]: info: Sep 09 18:27:25 volumio-wld volumio[819]: ---------------------------- MPD announces system playlist update Sep 09 18:27:25 volumio-wld volumio[819]: info: Ignoring MPD Status Update Sep 09 18:27:25 volumio-wld volumio[819]: info: Sep 09 18:27:25 volumio-wld volumio[819]: ---------------------------- MPD announces state update: player Sep 09 18:27:25 volumio-wld volumio[819]: info: ControllerMpd::getState Sep 09 18:27:25 volumio-wld volumio[819]: verbose: ControllerMpd::sendMpdCommand status Sep 09 18:27:25 volumio-wld volumio[819]: info: ------------------------------ 14ms Sep 09 18:27:25 volumio-wld volumio[819]: info: sendMpdCommand status took 13 milliseconds Sep 09 18:27:25 volumio-wld volumio[819]: info: ------------------------------ 12ms Sep 09 18:27:25 volumio-wld volumio[819]: info: sendMpdCommand status took 10 milliseconds Sep 09 18:27:25 volumio-wld volumio[819]: verbose: ControllerMpd::parseState Sep 09 18:27:25 volumio-wld volumio[819]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 18:27:25 volumio-wld volumio[819]: verbose: ControllerMpd::parseState Sep 09 18:27:25 volumio-wld volumio[819]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 18:27:25 volumio-wld volumio[819]: info: Sep 09 18:27:25 volumio-wld volumio[819]: ---------------------------- MPD announces system playlist update Sep 09 18:27:25 volumio-wld volumio[819]: info: Ignoring MPD Status Update Sep 09 18:27:25 volumio-wld volumio[819]: info: Sep 09 18:27:25 volumio-wld volumio[819]: ---------------------------- MPD announces state update: player Sep 09 18:27:25 volumio-wld volumio[819]: info: ControllerMpd::getState Sep 09 18:27:25 volumio-wld volumio[819]: verbose: ControllerMpd::sendMpdCommand status Sep 09 18:27:25 volumio-wld volumio[819]: info: Sep 09 18:27:25 volumio-wld volumio[819]: ---------------------------- MPD announces system playlist update Sep 09 18:27:25 volumio-wld volumio[819]: info: Ignoring MPD Status Update Sep 09 18:27:25 volumio-wld volumio[819]: info: Sep 09 18:27:25 volumio-wld volumio[819]: ---------------------------- MPD announces state update: player Sep 09 18:27:25 volumio-wld volumio[819]: info: ControllerMpd::getState Sep 09 18:27:25 volumio-wld volumio[819]: verbose: ControllerMpd::sendMpdCommand status Sep 09 18:27:25 volumio-wld volumio[819]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 09 18:27:25 volumio-wld volumio[819]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 09 18:27:25 volumio-wld volumio[819]: info: ------------------------------ 8ms Sep 09 18:27:25 volumio-wld volumio[819]: info: sendMpdCommand status took 6 milliseconds Sep 09 18:27:25 volumio-wld volumio[819]: info: ------------------------------ 6ms Sep 09 18:27:25 volumio-wld volumio[819]: info: sendMpdCommand status took 6 milliseconds Sep 09 18:27:25 volumio-wld volumio[819]: verbose: ControllerMpd::parseTrackInfo Sep 09 18:27:25 volumio-wld volumio[819]: verbose: ControllerMpd::parseTrackInfo Sep 09 18:27:25 volumio-wld volumio[819]: verbose: ControllerMpd::parseState Sep 09 18:27:25 volumio-wld volumio[819]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 18:27:25 volumio-wld volumio[819]: verbose: ControllerMpd::parseState Sep 09 18:27:25 volumio-wld volumio[819]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 18:27:25 volumio-wld volumio[819]: info: ControllerMpd::pushState Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreCommandRouter::servicePushState Sep 09 18:27:25 volumio-wld volumio[819]: info: CorePlayQueue::getTrack 1 Sep 09 18:27:25 volumio-wld volumio[819]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":158,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.小城故事.wav","artist":null,"album":null,"uri":"INTERNAL/music/邓丽君/02.小城故事.wav","trackType":"wav"} Sep 09 18:27:25 volumio-wld volumio[819]: verbose: CURRENT POSITION 1 Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreStateMachine::syncState stateService play Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreStateMachine::syncState currentStatus play Sep 09 18:27:25 volumio-wld volumio[819]: info: Received an update from plugin. extracting info from payload Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreStateMachine::pushState Sep 09 18:27:25 volumio-wld volumio[819]: info: CorePlayQueue::getTrack 1 Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreCommandRouter::volumioPushState Sep 09 18:27:25 volumio-wld systemd[1]: Starting Flexible IR remote input/output application support... Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreStateMachine::pushState Sep 09 18:27:25 volumio-wld volumio[819]: info: CorePlayQueue::getTrack 1 Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreCommandRouter::volumioPushState Sep 09 18:27:25 volumio-wld volumio[819]: info: ControllerMpd::pushState Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreCommandRouter::servicePushState Sep 09 18:27:25 volumio-wld volumio[819]: info: CorePlayQueue::getTrack 1 Sep 09 18:27:25 volumio-wld volumio[819]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":158,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.小城故事.wav","artist":null,"album":null,"uri":"INTERNAL/music/邓丽君/02.小城故事.wav","trackType":"wav"} Sep 09 18:27:25 volumio-wld volumio[819]: verbose: CURRENT POSITION 1 Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreStateMachine::syncState stateService play Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreStateMachine::syncState currentStatus play Sep 09 18:27:25 volumio-wld volumio[819]: info: Received an update from plugin. extracting info from payload Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreStateMachine::pushState Sep 09 18:27:25 volumio-wld volumio[819]: info: CorePlayQueue::getTrack 1 Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreCommandRouter::volumioPushState Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreStateMachine::pushState Sep 09 18:27:25 volumio-wld volumio[819]: info: CorePlayQueue::getTrack 1 Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreCommandRouter::volumioPushState Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Info: lircd: Opening log, level: Info Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: Version: lircd 0.10.1 Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: System info: Linux volumio-wld 6.1.69-v7l+ #1710 SMP Thu Dec 21 13:17:38 GMT 2023 armv7l GNU/Linux Sep 09 18:27:25 volumio-wld volumio[819]: info: ------------------------------ 178ms Sep 09 18:27:25 volumio-wld volumio[819]: info: ------------------------------ 175ms Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreStateMachine::pushState Sep 09 18:27:25 volumio-wld volumio[819]: info: CorePlayQueue::getTrack 1 Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreCommandRouter::volumioPushState Sep 09 18:27:25 volumio-wld volumio[819]: info: sendMpdCommand playlistinfo took 201 milliseconds Sep 09 18:27:25 volumio-wld volumio[819]: info: sendMpdCommand playlistinfo took 203 milliseconds Sep 09 18:27:25 volumio-wld volumio[819]: verbose: ControllerMpd::parseTrackInfo Sep 09 18:27:25 volumio-wld volumio[819]: verbose: ControllerMpd::parseTrackInfo Sep 09 18:27:25 volumio-wld volumio[819]: info: ControllerMpd::pushState Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreCommandRouter::servicePushState Sep 09 18:27:25 volumio-wld volumio[819]: info: CorePlayQueue::getTrack 1 Sep 09 18:27:25 volumio-wld volumio[819]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":158,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.小城故事.wav","artist":null,"album":null,"uri":"INTERNAL/music/邓丽君/02.小城故事.wav","trackType":"wav"} Sep 09 18:27:25 volumio-wld volumio[819]: verbose: CURRENT POSITION 1 Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreStateMachine::syncState stateService play Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreStateMachine::syncState currentStatus play Sep 09 18:27:25 volumio-wld volumio[819]: info: Received an update from plugin. extracting info from payload Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreStateMachine::pushState Sep 09 18:27:25 volumio-wld volumio[819]: info: CorePlayQueue::getTrack 1 Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreCommandRouter::volumioPushState Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreStateMachine::pushState Sep 09 18:27:25 volumio-wld volumio[819]: info: CorePlayQueue::getTrack 1 Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreCommandRouter::volumioPushState Sep 09 18:27:25 volumio-wld volumio[819]: info: ControllerMpd::pushState Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreCommandRouter::servicePushState Sep 09 18:27:25 volumio-wld volumio[819]: info: CorePlayQueue::getTrack 1 Sep 09 18:27:25 volumio-wld volumio[819]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":158,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.小城故事.wav","artist":null,"album":null,"uri":"INTERNAL/music/邓丽君/02.小城故事.wav","trackType":"wav"} Sep 09 18:27:25 volumio-wld volumio[819]: verbose: CURRENT POSITION 1 Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreStateMachine::syncState stateService play Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreStateMachine::syncState currentStatus play Sep 09 18:27:25 volumio-wld volumio[819]: info: Received an update from plugin. extracting info from payload Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreStateMachine::pushState Sep 09 18:27:25 volumio-wld volumio[819]: info: CorePlayQueue::getTrack 1 Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreCommandRouter::volumioPushState Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreStateMachine::pushState Sep 09 18:27:25 volumio-wld volumio[819]: info: CorePlayQueue::getTrack 1 Sep 09 18:27:25 volumio-wld volumio[819]: info: CoreCommandRouter::volumioPushState Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Info: Initial device: /dev/lirc0 Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Info: Initial device: /dev/lirc0 Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Info: [lirc] protocol is enabled Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: Options: driver: default Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: Options: output: /var/run/lirc/lircd Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: Options: nodaemon: 1 Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: Options: logfile: syslog Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: Options: immediate-init: 0 Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: Options: permission: 666 Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: Options: driver-options: Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: Options: listen: 0 Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: Options: connect: (null) Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: Options: userelease: 0 Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: Options: effective_user: (null) Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: Options: release_suffix: _EVUP Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: Options: allow_simulate: 0 Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: Options: repeat_max: 600 Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: Options: configfile: /etc/lirc/lircd.conf Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: Options: dynamic_codes: (null) Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: Current driver: default Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: Driver API version: 3 Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: Driver version: 0.10.0 Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Sep 09 18:27:25 volumio-wld lircd[8191]: lircd-0.10.1[8191]: Info: lircd: Opening log, level: Info Sep 09 18:27:25 volumio-wld lircd[8191]: lircd-0.10.1[8191]: Warning: Running as root Sep 09 18:27:25 volumio-wld lircd[8191]: lircd-0.10.1[8191]: Info: Using remote: lircd.conf. Sep 09 18:27:25 volumio-wld lircd[8191]: lircd-0.10.1[8191]: Notice: lircd(default) ready, using /var/run/lirc/lircd Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Info: lircd: Opening log, level: Info Sep 09 18:27:25 volumio-wld systemd[1]: Started Flexible IR remote input/output application support. Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Warning: Running as root Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Info: Using remote: lircd.conf. Sep 09 18:27:25 volumio-wld lircd-0.10.1[8191]: Notice: lircd(default) ready, using /var/run/lirc/lircd Sep 09 18:27:25 volumio-wld sudo[8187]: pam_unix(sudo:session): session closed for user root Sep 09 18:27:26 volumio-wld volumio[819]: info: ------------------------------ 446ms Sep 09 18:27:26 volumio-wld volumio[819]: info: ------------------------------ 446ms Sep 09 18:27:26 volumio-wld volumio[819]: info: ir_controller: systemctl start lircd.service succeeded. Sep 09 18:27:26 volumio-wld sudo[8210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Sep 09 18:27:26 volumio-wld sudo[8210]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 18:27:26 volumio-wld systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Sep 09 18:27:26 volumio-wld lircd[8191]: lircd-0.10.1[8191]: Notice: accepted new client on /var/run/lirc/lircd Sep 09 18:27:26 volumio-wld lircd-0.10.1[8191]: Notice: accepted new client on /var/run/lirc/lircd Sep 09 18:27:26 volumio-wld lircd[8191]: lircd-0.10.1[8191]: Info: [lirc] protocol is enabled Sep 09 18:27:26 volumio-wld lircd-0.10.1[8191]: Info: [lirc] protocol is enabled Sep 09 18:27:26 volumio-wld sudo[8210]: pam_unix(sudo:session): session closed for user root Sep 09 18:27:26 volumio-wld volumio[819]: info: ir_controller: systemctl restart irexec.service succeeded. Sep 09 18:27:29 volumio-wld volumio[819]: info: CoreCommandRouter::volumioGetState Sep 09 18:27:29 volumio-wld volumio[819]: info: CorePlayQueue::getTrack 1 Sep 09 18:27:36 volumio-wld volumio[819]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Sep 09 18:27:36 volumio-wld volumio[819]: info: Retrying Login Due to Network Error Sep 09 18:27:37 volumio-wld volumio[819]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 36 Sep 09 18:27:38 volumio-wld volumio[819]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 37 Sep 09 18:27:39 volumio-wld volumio[819]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 37 Sep 09 18:27:40 volumio-wld volumio[819]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 38 Sep 09 18:27:40 volumio-wld volumio[819]: info: CoreCommandRouter::volumioGetState Sep 09 18:27:40 volumio-wld volumio[819]: info: CorePlayQueue::getTrack 1 Sep 09 18:27:40 volumio-wld volumio[819]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 09 18:27:40 volumio-wld volumio[819]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 09 18:27:40 volumio-wld volumio[819]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 09 18:27:40 volumio-wld volumio[819]: info: Received Get System Version Sep 09 18:27:40 volumio-wld volumio[819]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 09 18:27:40 volumio-wld volumio[819]: info: Received Get System Info Sep 09 18:27:40 volumio-wld volumio[819]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 18:27:40 volumio-wld volumio[819]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 18:27:40 volumio-wld volumio[819]: info: Discovery: Getting this device information Sep 09 18:27:40 volumio-wld volumio[819]: info: CoreCommandRouter::volumioGetState Sep 09 18:27:40 volumio-wld volumio[819]: info: CorePlayQueue::getTrack 1 Sep 09 18:27:40 volumio-wld volumio[819]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 18:27:42 volumio-wld volumio[819]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 09 18:27:43 volumio-wld volumio[819]: info: CoreCommandRouter::Close All Modals sent Sep 09 18:27:46 volumio-wld volumio[819]: info: MyVolumio login type: Token Sep 09 18:27:48 volumio-wld volumio[819]: info: Enabling plugin rotaryencoder Sep 09 18:27:48 volumio-wld volumio[819]: info: Loading plugin "rotaryencoder"... Sep 09 18:27:49 volumio-wld volumio[819]: error: !!!! WARNING !!!! Sep 09 18:27:49 volumio-wld volumio[819]: error: The plugin user_interface/rotaryencoder failed to load, setting it to stopped. Error: Error: Cannot find module 'onoff-rotary' Sep 09 18:27:49 volumio-wld volumio[819]: Require stack: Sep 09 18:27:49 volumio-wld volumio[819]: - /data/plugins/user_interface/rotaryencoder/index.js Sep 09 18:27:49 volumio-wld volumio[819]: - /volumio/app/pluginmanager.js Sep 09 18:27:49 volumio-wld volumio[819]: - /volumio/app/index.js Sep 09 18:27:49 volumio-wld volumio[819]: - /volumio/index.js Sep 09 18:27:49 volumio-wld volumio[819]: error: Stack trace: Error: Cannot find module 'onoff-rotary' Sep 09 18:27:49 volumio-wld volumio[819]: Require stack: Sep 09 18:27:49 volumio-wld volumio[819]: - /data/plugins/user_interface/rotaryencoder/index.js Sep 09 18:27:49 volumio-wld volumio[819]: - /volumio/app/pluginmanager.js Sep 09 18:27:49 volumio-wld volumio[819]: - /volumio/app/index.js Sep 09 18:27:49 volumio-wld volumio[819]: - /volumio/index.js Sep 09 18:27:49 volumio-wld volumio[819]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:880:15) Sep 09 18:27:49 volumio-wld volumio[819]: at Function.Module._load (internal/modules/cjs/loader.js:725:27) Sep 09 18:27:49 volumio-wld volumio[819]: at Module.require (internal/modules/cjs/loader.js:952:19) Sep 09 18:27:49 volumio-wld volumio[819]: at require (internal/modules/cjs/helpers.js:88:18) Sep 09 18:27:49 volumio-wld volumio[819]: at Object. (/data/plugins/user_interface/rotaryencoder/index.js:13:21) Sep 09 18:27:49 volumio-wld volumio[819]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Sep 09 18:27:49 volumio-wld volumio[819]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Sep 09 18:27:49 volumio-wld volumio[819]: at Module.load (internal/modules/cjs/loader.js:928:32) Sep 09 18:27:49 volumio-wld volumio[819]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Sep 09 18:27:49 volumio-wld volumio[819]: at Module.require (internal/modules/cjs/loader.js:952:19) Sep 09 18:27:49 volumio-wld volumio[819]: at require (internal/modules/cjs/helpers.js:88:18) Sep 09 18:27:49 volumio-wld volumio[819]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Sep 09 18:27:49 volumio-wld volumio[819]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Sep 09 18:27:49 volumio-wld volumio[819]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 09 18:27:49 volumio-wld volumio[819]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 09 18:27:49 volumio-wld volumio[819]: error: !!!! WARNING !!!! Sep 09 18:27:49 volumio-wld volumio[819]: info: Done. Sep 09 18:27:49 volumio-wld volumio[819]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 39 Sep 09 18:28:12 volumio-wld volumio[819]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 38 Sep 09 18:28:12 volumio-wld volumio[819]: info: Downloading plugin at http://127.0.0.1:3000/plugin-serve/gpio_radio_buttons.zip Sep 09 18:28:12 volumio-wld volumio[819]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 09 18:28:12 volumio-wld volumio[819]: TypeError: Cannot read property 'broadcastMessage' of null Sep 09 18:28:12 volumio-wld volumio[819]: at /volumio/app/index.js:1509:32 Sep 09 18:28:12 volumio-wld volumio[819]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Sep 09 18:28:12 volumio-wld volumio[819]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Sep 09 18:28:12 volumio-wld volumio[819]: at CoreCommandRouter.broadcastMessage (/volumio/app/index.js:1507:13) Sep 09 18:28:12 volumio-wld volumio[819]: at PluginManager.pushMessage (/volumio/app/pluginmanager.js:1594:20) Sep 09 18:28:12 volumio-wld volumio[819]: at PluginManager.installPlugin (/volumio/app/pluginmanager.js:776:8) Sep 09 18:28:12 volumio-wld volumio[819]: at CoreCommandRouter.installPlugin (/volumio/app/index.js:1428:22) Sep 09 18:28:12 volumio-wld volumio[819]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1167:47) Sep 09 18:28:12 volumio-wld volumio[819]: at Socket.emit (events.js:315:20) Sep 09 18:28:12 volumio-wld volumio[819]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Sep 09 18:28:12 volumio-wld volumio[819]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 09 18:28:12 volumio-wld volumio[819]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 09 18:28:13 volumio-wld sudo[8419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-09 18:27 Sep 09 18:28:13 volumio-wld sudo[8419]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"