-- Logs begin at Wed 2024-11-27 16:50:44 CST, end at Wed 2024-11-27 18:01:29 CST. -- Nov 27 18:00:09 volumio sudo[4034]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/mv /usr/share/X11/xorg.conf.d/45-evdev.conf . Nov 27 18:00:09 volumio sudo[4034]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Nov 27 18:00:09 volumio sudo[4034]: pam_unix(sudo:session): session closed for user root Nov 27 18:00:09 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Nov 27 18:00:09 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:09 volumio volumio[907]: info: Listing playlists Nov 27 18:00:09 volumio volumio[907]: info: Listing playlists Nov 27 18:00:18 volumio volumio[907]: info: Enabling plugin touch_display Nov 27 18:00:18 volumio volumio[907]: info: Loading plugin "touch_display"... Nov 27 18:00:18 volumio volumio[907]: info: Applying required configuration parameters for plugin touch_display Nov 27 18:00:18 volumio volumio[907]: info: PLUGIN START: touch_display Nov 27 18:00:18 volumio volumio[907]: info: Loading i18n strings for locale zh Nov 27 18:00:18 volumio volumio[907]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 27 18:00:18 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 27 18:00:18 volumio sudo[4084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Nov 27 18:00:18 volumio sudo[4084]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:00:18 volumio sudo[4087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Nov 27 18:00:18 volumio sudo[4087]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:00:18 volumio systemd[1]: Stopping Getty on tty1... Nov 27 18:00:18 volumio systemd[1]: getty@tty1.service: Main process exited, code=killed, status=15/TERM Nov 27 18:00:18 volumio systemd[1]: getty@tty1.service: Succeeded. Nov 27 18:00:18 volumio systemd[1]: Stopped Getty on tty1. Nov 27 18:00:18 volumio volumio[907]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Nov 27 18:00:18 volumio sudo[4090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 27 18:00:18 volumio sudo[4090]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:00:18 volumio sudo[4084]: pam_unix(sudo:session): session closed for user root Nov 27 18:00:18 volumio systemd[1]: Reloading. Nov 27 18:00:18 volumio volumio[907]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Nov 27 18:00:18 volumio volumio[907]: info: touch_display: systemctl stop getty@tty1.service succeeded. Nov 27 18:00:18 volumio sudo[4093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Nov 27 18:00:18 volumio sudo[4093]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:00:18 volumio sudo[4093]: pam_unix(sudo:session): session closed for user root Nov 27 18:00:18 volumio volumio[907]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Nov 27 18:00:18 volumio sudo[4095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Nov 27 18:00:18 volumio sudo[4095]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:00:18 volumio sudo[4095]: pam_unix(sudo:session): session closed for user root Nov 27 18:00:18 volumio volumio[907]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Nov 27 18:00:18 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Nov 27 18:00:19 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Nov 27 18:00:19 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:19 volumio sudo[4087]: pam_unix(sudo:session): session closed for user root Nov 27 18:00:19 volumio volumio[907]: info: touch_display: systemctl disable getty@tty1.service succeeded. Nov 27 18:00:19 volumio systemd[1]: Reloading. Nov 27 18:00:19 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Nov 27 18:00:20 volumio sudo[4090]: pam_unix(sudo:session): session closed for user root Nov 27 18:00:20 volumio volumio[907]: info: touch_display: systemctl daemon-reload succeeded. Nov 27 18:00:20 volumio sudo[4144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Nov 27 18:00:20 volumio sudo[4144]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 18:00:20 volumio systemd[1]: Started Volumio Kiosk. Nov 27 18:00:20 volumio sudo[4144]: pam_unix(sudo:session): session closed for user root Nov 27 18:00:20 volumio volumio[907]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Nov 27 18:00:20 volumio volumio[907]: info: touch_display: Volumio Kiosk started. Nov 27 18:00:20 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Nov 27 18:00:20 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:20 volumio volumio[907]: info: Done. Nov 27 18:00:20 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Nov 27 18:00:20 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:20 volumio volumio[907]: info: touch_display: X display number found: Nov 27 18:00:20 volumio startx[4153]: X.Org X Server 1.20.4 Nov 27 18:00:20 volumio startx[4153]: X Protocol Version 11, Revision 0 Nov 27 18:00:20 volumio startx[4153]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Nov 27 18:00:20 volumio startx[4153]: Current Operating System: Linux volumio 6.6.56-v7+ #1803 SMP Thu Oct 10 14:08:55 BST 2024 armv7l Nov 27 18:00:20 volumio startx[4153]: Kernel command line: coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835.enable_headphones=0 bcm2708_fb.fbwidth=480 bcm2708_fb.fbheight=320 bcm2708_fb.fbswap=1 smsc95xx.macaddr=B8:27:EB:20:19:2F vc_mem.mem_base=0x1ec00000 vc_mem.mem_size=0x20000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=826680f7-a407-4ab9-8bd5-1855bda526fd imgfile=/volumio_current.sqsh bootpart=UUID=FD90-ED9E datapart=UUID=fecb0823-2e5b-4bba-9498-dd37b5084148 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Nov 27 18:00:20 volumio startx[4153]: Build Date: 04 April 2023 07:50:56AM Nov 27 18:00:20 volumio startx[4153]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Nov 27 18:00:20 volumio startx[4153]: Current version of pixman: 0.36.0 Nov 27 18:00:20 volumio startx[4153]: Before reporting problems, check http://wiki.x.org Nov 27 18:00:20 volumio startx[4153]: to make sure that you have the latest version. Nov 27 18:00:20 volumio startx[4153]: Markers: (--) probed, (**) from config file, (==) default setting, Nov 27 18:00:20 volumio startx[4153]: (++) from command line, (!!) notice, (II) informational, Nov 27 18:00:20 volumio startx[4153]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Nov 27 18:00:20 volumio startx[4153]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Nov 27 18:00:20 2024 Nov 27 18:00:20 volumio startx[4153]: (==) Using config directory: "/etc/X11/xorg.conf.d" Nov 27 18:00:20 volumio startx[4153]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Nov 27 18:00:20 volumio volumio[907]: info: touch_display: X display number found: 0 Nov 27 18:00:21 volumio volumio[907]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 27 18:00:21 volumio volumio[907]: info: touch_display: X display number found: 0 Nov 27 18:00:23 volumio volumio[907]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 27 18:00:30 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Nov 27 18:00:30 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:30 volumio volumio[907]: info: Listing playlists Nov 27 18:00:30 volumio volumio[907]: info: Listing playlists Nov 27 18:00:36 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:36 volumio volumio[907]: info: CorePlayQueue::getTrack 4 Nov 27 18:00:36 volumio volumio[907]: info: Prefetching next song Nov 27 18:00:37 volumio volumio[907]: info: DOING PREFETCH IN MPD Nov 27 18:00:38 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand add "NAS/SACD/【经典国语】老男人的情怀老男人的歌/伍思凯-特别的爱给特别的你.flac" Nov 27 18:00:38 volumio volumio[907]: info: Nov 27 18:00:38 volumio volumio[907]: ---------------------------- MPD announces system playlist update Nov 27 18:00:38 volumio volumio[907]: info: Ignoring MPD Status Update Nov 27 18:00:38 volumio volumio[907]: info: sendMpdCommand add "NAS/SACD/【经典国语】老男人的情怀老男人的歌/伍思凯-特别的爱给特别的你.flac" took 5 milliseconds Nov 27 18:00:38 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand consume 1 Nov 27 18:00:39 volumio volumio[907]: info: Nov 27 18:00:39 volumio volumio[907]: ---------------------------- MPD announces system playlist update Nov 27 18:00:39 volumio volumio[907]: info: Ignoring MPD Status Update Nov 27 18:00:39 volumio volumio[907]: info: Nov 27 18:00:39 volumio volumio[907]: ---------------------------- MPD announces system playlist update Nov 27 18:00:41 volumio volumio[907]: info: Ignoring MPD Status Update Nov 27 18:00:41 volumio volumio[907]: info: Nov 27 18:00:41 volumio volumio[907]: ---------------------------- MPD announces state update: options Nov 27 18:00:41 volumio volumio[907]: info: ------------------------------ 2623ms Nov 27 18:00:41 volumio volumio[907]: info: sendMpdCommand consume 1 took 2621 milliseconds Nov 27 18:00:41 volumio volumio[907]: info: ControllerMpd::getState Nov 27 18:00:41 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status Nov 27 18:00:41 volumio volumio[907]: info: ------------------------------ 1512ms Nov 27 18:00:41 volumio volumio[907]: info: ------------------------------ 1511ms Nov 27 18:00:41 volumio volumio[907]: info: Nov 27 18:00:41 volumio volumio[907]: ---------------------------- MPD announces state update: options Nov 27 18:00:41 volumio volumio[907]: info: ControllerMpd::getState Nov 27 18:00:41 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status Nov 27 18:00:41 volumio volumio[907]: info: Nov 27 18:00:41 volumio volumio[907]: ---------------------------- MPD announces state update: options Nov 27 18:00:41 volumio volumio[907]: info: ControllerMpd::getState Nov 27 18:00:41 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status Nov 27 18:00:41 volumio volumio[907]: info: Nov 27 18:00:41 volumio volumio[907]: ---------------------------- MPD announces system playlist update Nov 27 18:00:41 volumio volumio[907]: info: Ignoring MPD Status Update Nov 27 18:00:41 volumio volumio[907]: info: Nov 27 18:00:41 volumio volumio[907]: ---------------------------- MPD announces state update: player Nov 27 18:00:42 volumio volumio[907]: info: sendMpdCommand status took 843 milliseconds Nov 27 18:00:42 volumio volumio[907]: info: sendMpdCommand status took 842 milliseconds Nov 27 18:00:42 volumio volumio[907]: info: sendMpdCommand status took 840 milliseconds Nov 27 18:00:42 volumio volumio[907]: info: ControllerMpd::getState Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::parseState Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::parseState Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::parseState Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 18:00:42 volumio volumio[907]: info: Nov 27 18:00:42 volumio volumio[907]: ---------------------------- MPD announces system playlist update Nov 27 18:00:42 volumio volumio[907]: info: Ignoring MPD Status Update Nov 27 18:00:42 volumio volumio[907]: info: Nov 27 18:00:42 volumio volumio[907]: ---------------------------- MPD announces state update: player Nov 27 18:00:42 volumio volumio[907]: info: ControllerMpd::getState Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status Nov 27 18:00:42 volumio volumio[907]: info: Nov 27 18:00:42 volumio volumio[907]: ---------------------------- MPD announces system playlist update Nov 27 18:00:42 volumio volumio[907]: info: Ignoring MPD Status Update Nov 27 18:00:42 volumio volumio[907]: info: Nov 27 18:00:42 volumio volumio[907]: ---------------------------- MPD announces state update: player Nov 27 18:00:42 volumio volumio[907]: info: ControllerMpd::getState Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status Nov 27 18:00:42 volumio volumio[907]: info: ------------------------------ 291ms Nov 27 18:00:42 volumio volumio[907]: info: sendMpdCommand status took 29 milliseconds Nov 27 18:00:42 volumio volumio[907]: info: sendMpdCommand playlistinfo took 249 milliseconds Nov 27 18:00:42 volumio volumio[907]: info: ------------------------------ 248ms Nov 27 18:00:42 volumio volumio[907]: info: sendMpdCommand status took 246 milliseconds Nov 27 18:00:42 volumio volumio[907]: info: ------------------------------ 246ms Nov 27 18:00:42 volumio volumio[907]: info: sendMpdCommand status took 244 milliseconds Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::parseState Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::parseTrackInfo Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::parseTrackInfo Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::parseTrackInfo Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::parseState Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::parseState Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 18:00:42 volumio volumio[907]: info: ControllerMpd::pushState Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::servicePushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: verbose: STATE SERVICE {"status":"play","position":0,"seek":311966,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"247 Kbps","isStreaming":false,"title":"特别的爱给特别的你","artist":"伍思凯","album":"国语老歌·男人篇","uri":"NAS/SACD/【经典国语】老男人的情怀老男人的歌/伍思凯-特别的爱给特别的你.flac","trackType":"flac"} Nov 27 18:00:42 volumio volumio[907]: verbose: CURRENT POSITION 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::syncState stateService play Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus play Nov 27 18:00:42 volumio volumio[907]: info: Received an update from plugin. extracting info from payload Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::pushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::pushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Nov 27 18:00:42 volumio volumio[907]: info: ControllerMpd::pushState Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::servicePushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: verbose: STATE SERVICE {"status":"play","position":0,"seek":311966,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"247 Kbps","isStreaming":false,"title":"特别的爱给特别的你","artist":"伍思凯","album":"国语老歌·男人篇","uri":"NAS/SACD/【经典国语】老男人的情怀老男人的歌/伍思凯-特别的爱给特别的你.flac","trackType":"flac"} Nov 27 18:00:42 volumio volumio[907]: verbose: CURRENT POSITION 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::syncState stateService play Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus play Nov 27 18:00:42 volumio volumio[907]: info: Received an update from plugin. extracting info from payload Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::pushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::pushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Nov 27 18:00:42 volumio volumio[907]: info: ControllerMpd::pushState Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::servicePushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: verbose: STATE SERVICE {"status":"play","position":0,"seek":311966,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"247 Kbps","isStreaming":false,"title":"特别的爱给特别的你","artist":"伍思凯","album":"国语老歌·男人篇","uri":"NAS/SACD/【经典国语】老男人的情怀老男人的歌/伍思凯-特别的爱给特别的你.flac","trackType":"flac"} Nov 27 18:00:42 volumio volumio[907]: verbose: CURRENT POSITION 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::syncState stateService play Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus play Nov 27 18:00:42 volumio volumio[907]: info: Received an update from plugin. extracting info from payload Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::pushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::pushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Nov 27 18:00:42 volumio volumio[907]: info: ------------------------------ 1337ms Nov 27 18:00:42 volumio volumio[907]: info: ------------------------------ 1334ms Nov 27 18:00:42 volumio volumio[907]: info: ------------------------------ 1334ms Nov 27 18:00:42 volumio volumio[907]: Upnp client error: Error: This socket has been ended by the other party Nov 27 18:00:42 volumio volumio[907]: info: Nov 27 18:00:42 volumio volumio[907]: ---------------------------- MPD announces state update: player Nov 27 18:00:42 volumio volumio[907]: info: ControllerMpd::getState Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status Nov 27 18:00:42 volumio volumio[907]: info: sendMpdCommand playlistinfo took 261 milliseconds Nov 27 18:00:42 volumio volumio[907]: info: sendMpdCommand playlistinfo took 260 milliseconds Nov 27 18:00:42 volumio volumio[907]: info: sendMpdCommand playlistinfo took 261 milliseconds Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::parseTrackInfo Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::parseTrackInfo Nov 27 18:00:42 volumio volumio[907]: info: ControllerMpd::pushState Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::servicePushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":296,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"特别的爱给特别的你","artist":"伍思凯","album":"国语老歌·男人篇","uri":"NAS/SACD/【经典国语】老男人的情怀老男人的歌/伍思凯-特别的爱给特别的你.flac","trackType":"flac"} Nov 27 18:00:42 volumio volumio[907]: verbose: CURRENT POSITION 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::syncState stateService play Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus play Nov 27 18:00:42 volumio volumio[907]: info: Received an update from plugin. extracting info from payload Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::pushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::pushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Nov 27 18:00:42 volumio volumio[907]: info: ControllerMpd::pushState Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::servicePushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":296,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"特别的爱给特别的你","artist":"伍思凯","album":"国语老歌·男人篇","uri":"NAS/SACD/【经典国语】老男人的情怀老男人的歌/伍思凯-特别的爱给特别的你.flac","trackType":"flac"} Nov 27 18:00:42 volumio volumio[907]: verbose: CURRENT POSITION 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::syncState stateService play Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus play Nov 27 18:00:42 volumio volumio[907]: info: Received an update from plugin. extracting info from payload Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::pushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::pushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Nov 27 18:00:42 volumio volumio[907]: info: ControllerMpd::pushState Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::servicePushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":296,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"特别的爱给特别的你","artist":"伍思凯","album":"国语老歌·男人篇","uri":"NAS/SACD/【经典国语】老男人的情怀老男人的歌/伍思凯-特别的爱给特别的你.flac","trackType":"flac"} Nov 27 18:00:42 volumio volumio[907]: verbose: CURRENT POSITION 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::syncState stateService play Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus play Nov 27 18:00:42 volumio volumio[907]: info: Received an update from plugin. extracting info from payload Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::pushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::pushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Nov 27 18:00:42 volumio volumio[907]: info: ------------------------------ 918ms Nov 27 18:00:42 volumio volumio[907]: info: ------------------------------ 651ms Nov 27 18:00:42 volumio volumio[907]: info: ------------------------------ 649ms Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: info: Nov 27 18:00:42 volumio volumio[907]: ---------------------------- MPD announces state update: player Nov 27 18:00:42 volumio volumio[907]: info: ControllerMpd::getState Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status Nov 27 18:00:42 volumio volumio[907]: info: Nov 27 18:00:42 volumio volumio[907]: ---------------------------- MPD announces state update: player Nov 27 18:00:42 volumio volumio[907]: info: ControllerMpd::getState Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status Nov 27 18:00:42 volumio volumio[907]: info: sendMpdCommand status took 238 milliseconds Nov 27 18:00:42 volumio volumio[907]: info: sendMpdCommand status took 88 milliseconds Nov 27 18:00:42 volumio volumio[907]: info: sendMpdCommand status took 86 milliseconds Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::parseState Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::parseState Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::parseState Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 27 18:00:42 volumio volumio[907]: info: sendMpdCommand playlistinfo took 23 milliseconds Nov 27 18:00:42 volumio volumio[907]: info: sendMpdCommand playlistinfo took 23 milliseconds Nov 27 18:00:42 volumio volumio[907]: info: sendMpdCommand playlistinfo took 23 milliseconds Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::parseTrackInfo Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::parseTrackInfo Nov 27 18:00:42 volumio volumio[907]: verbose: ControllerMpd::parseTrackInfo Nov 27 18:00:42 volumio volumio[907]: info: ControllerMpd::pushState Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::servicePushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: verbose: STATE SERVICE {"status":"play","position":0,"seek":915,"duration":296,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"951 Kbps","isStreaming":false,"title":"特别的爱给特别的你","artist":"伍思凯","album":"国语老歌·男人篇","uri":"NAS/SACD/【经典国语】老男人的情怀老男人的歌/伍思凯-特别的爱给特别的你.flac","trackType":"flac"} Nov 27 18:00:42 volumio volumio[907]: verbose: CURRENT POSITION 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::syncState stateService play Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus play Nov 27 18:00:42 volumio volumio[907]: info: Received an update from plugin. extracting info from payload Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::pushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::pushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Nov 27 18:00:42 volumio volumio[907]: info: ControllerMpd::pushState Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::servicePushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1006,"duration":296,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"945 Kbps","isStreaming":false,"title":"特别的爱给特别的你","artist":"伍思凯","album":"国语老歌·男人篇","uri":"NAS/SACD/【经典国语】老男人的情怀老男人的歌/伍思凯-特别的爱给特别的你.flac","trackType":"flac"} Nov 27 18:00:42 volumio volumio[907]: verbose: CURRENT POSITION 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::syncState stateService play Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus play Nov 27 18:00:42 volumio volumio[907]: info: Received an update from plugin. extracting info from payload Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::pushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::pushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Nov 27 18:00:42 volumio volumio[907]: info: ControllerMpd::pushState Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::servicePushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1006,"duration":296,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"945 Kbps","isStreaming":false,"title":"特别的爱给特别的你","artist":"伍思凯","album":"国语老歌·男人篇","uri":"NAS/SACD/【经典国语】老男人的情怀老男人的歌/伍思凯-特别的爱给特别的你.flac","trackType":"flac"} Nov 27 18:00:42 volumio volumio[907]: verbose: CURRENT POSITION 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::syncState stateService play Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus play Nov 27 18:00:42 volumio volumio[907]: info: Received an update from plugin. extracting info from payload Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::pushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Nov 27 18:00:42 volumio volumio[907]: info: CoreStateMachine::pushState Nov 27 18:00:42 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:42 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Nov 27 18:00:42 volumio volumio[907]: info: ------------------------------ 410ms Nov 27 18:00:42 volumio volumio[907]: info: ------------------------------ 260ms Nov 27 18:00:42 volumio volumio[907]: info: ------------------------------ 259ms Nov 27 18:00:44 volumio ntpd[688]: Soliciting pool server 2a03:8600::ee Nov 27 18:00:49 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Nov 27 18:00:49 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:00:49 volumio volumio[907]: info: Listing playlists Nov 27 18:00:59 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Nov 27 18:00:59 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:01:10 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Nov 27 18:01:10 volumio volumio[907]: info: CorePlayQueue::getTrack 3 Nov 27 18:01:10 volumio volumio[907]: info: Listing playlists Nov 27 18:01:22 volumio volumio[907]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 18:01:22 volumio volumio[907]: TypeError: Cannot read property 'ping' of undefined Nov 27 18:01:22 volumio volumio[907]: at Timeout._onTimeout (/data/plugins/music_service/volroon/node_modules/node-roon-api/transport-websocket.js:26:21) Nov 27 18:01:22 volumio volumio[907]: at listOnTimeout (internal/timers.js:554:17) Nov 27 18:01:22 volumio volumio[907]: at processTimers (internal/timers.js:497:7) Nov 27 18:01:22 volumio volumio[907]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 18:01:27 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Nov 27 18:01:29 volumio sudo[4691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-27 18:00 Nov 27 18:01:29 volumio sudo[4691]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"