-- Logs begin at Thu 2019-02-14 19:12:00 JST, end at Thu 2024-05-09 18:56:20 JST. -- May 09 18:55:13 volumio3-es9038b ntpd[716]: receive: Unexpected origin timestamp 0xe9e71360.238d6ebb does not match aorg 0000000000.00000000 from server@133.243.238.243 xmt 0xe9e71c01.13dff7e6 May 09 18:55:13 volumio3-es9038b volumio[1509]: verbose: New Socket.io Connection to 192.168.2.113 from 192.168.2.107 UA: okhttp/3.12.12 Total Clients: 5 May 09 18:55:13 volumio3-es9038b volumio[1509]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 09 18:55:13 volumio3-es9038b volumio[1509]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 09 18:55:13 volumio3-es9038b volumio[1509]: info: CoreCommandRouter::volumioGetVisibleSources May 09 18:55:13 volumio3-es9038b volumio[1509]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 18:55:13 volumio3-es9038b volumio[1509]: info: CoreCommandRouter::volumioGetState May 09 18:55:13 volumio3-es9038b volumio[1509]: info: CorePlayQueue::getTrack 0 May 09 18:55:13 volumio3-es9038b volumio[1509]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 09 18:55:13 volumio3-es9038b volumio[1509]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 09 18:55:13 volumio3-es9038b volumio[1509]: info: Received Get System Info May 09 18:55:13 volumio3-es9038b volumio[1509]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 18:55:13 volumio3-es9038b volumio[1509]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 18:55:13 volumio3-es9038b volumio[1509]: info: Discovery: Getting this device information May 09 18:55:13 volumio3-es9038b volumio[1509]: info: CoreCommandRouter::volumioGetState May 09 18:55:13 volumio3-es9038b volumio[1509]: info: CorePlayQueue::getTrack 0 May 09 18:55:13 volumio3-es9038b volumio[1509]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 18:55:13 volumio3-es9038b volumio[1509]: info: CoreCommandRouter::volumioGetState May 09 18:55:13 volumio3-es9038b volumio[1509]: info: CorePlayQueue::getTrack 0 May 09 18:55:13 volumio3-es9038b volumio[1509]: info: Listing playlists May 09 18:55:13 volumio3-es9038b volumio[1509]: info: CoreCommandRouter::volumioGetQueue May 09 18:55:13 volumio3-es9038b volumio[1509]: info: CoreStateMachine::getQueue May 09 18:55:13 volumio3-es9038b volumio[1509]: info: CorePlayQueue::getQueue May 09 18:55:13 volumio3-es9038b volumio[1509]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 18:55:13 volumio3-es9038b volumio[1509]: info: effect true May 09 18:55:13 volumio3-es9038b volumio[1509]: info: Dsp mode set is EQ15 May 09 18:55:13 volumio3-es9038b volumio[1509]: info: << convolution filters enabled May 09 18:55:13 volumio3-es9038b volumio[1509]: info: tools file to play : May 09 18:55:13 volumio3-es9038b volumio[1509]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 18:55:13 volumio3-es9038b volumio[1509]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 18:55:13 volumio3-es9038b volumio[1509]: info: effect true May 09 18:55:13 volumio3-es9038b volumio[1509]: info: Dsp mode set is EQ15 May 09 18:55:13 volumio3-es9038b volumio[1509]: info: effect true May 09 18:55:13 volumio3-es9038b volumio[1509]: info: Dsp mode set is EQ15 May 09 18:55:14 volumio3-es9038b ntpd[716]: Soliciting pool server 45.76.218.37 May 09 18:55:14 volumio3-es9038b volumio[1509]: info: << convolution filters enabled May 09 18:55:14 volumio3-es9038b volumio[1509]: info: tools file to play : May 09 18:55:14 volumio3-es9038b sudo[1631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 18:55:14 volumio3-es9038b sudo[1631]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:55:14 volumio3-es9038b volumio[1509]: info: << convolution filters enabled May 09 18:55:14 volumio3-es9038b volumio[1509]: info: tools file to play : May 09 18:55:14 volumio3-es9038b sudo[1631]: pam_unix(sudo:session): session closed for user root May 09 18:55:14 volumio3-es9038b sudo[1633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 18:55:14 volumio3-es9038b sudo[1633]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:55:14 volumio3-es9038b sudo[1633]: pam_unix(sudo:session): session closed for user root May 09 18:55:14 volumio3-es9038b sudo[1637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 09 18:55:14 volumio3-es9038b sudo[1637]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:55:14 volumio3-es9038b sudo[1637]: pam_unix(sudo:session): session closed for user root May 09 18:55:14 volumio3-es9038b volumio[1509]: info: Upmpdcli Daemon Started May 09 18:55:15 volumio3-es9038b ntpd[716]: Soliciting pool server 45.76.221.157 May 09 18:55:16 volumio3-es9038b volumio-remote-updater[565]: [2024-05-09 18:55:16] [connect] Successful connection May 09 18:55:16 volumio3-es9038b volumio-remote-updater[565]: [2024-05-09 18:55:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1715248516 101 May 09 18:55:16 volumio3-es9038b volumio[1509]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 6 May 09 18:55:29 volumio3-es9038b volumio[1509]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 09 18:55:29 volumio3-es9038b volumio[1509]: info: CURURI: albums:// May 09 18:55:29 volumio3-es9038b volumio[1509]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 18:55:29 volumio3-es9038b volumio[1509]: TypeError: Cannot read property 'sendCommand' of undefined May 09 18:55:29 volumio3-es9038b volumio[1509]: at /volumio/app/plugins/music_service/mpd/index.js:2986:22 May 09 18:55:29 volumio3-es9038b volumio[1509]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 09 18:55:29 volumio3-es9038b volumio[1509]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 18:55:29 volumio3-es9038b sudo[1650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-09 18:54 May 09 18:55:29 volumio3-es9038b sudo[1650]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:55:29 volumio3-es9038b sudo[1650]: pam_unix(sudo:session): session closed for user root May 09 18:55:31 volumio3-es9038b volumio-remote-updater[565]: [2024-05-09 18:55:31] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 09 18:55:31 volumio3-es9038b volumio-remote-updater[565]: [2024-05-09 18:55:31] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 09 18:55:31 volumio3-es9038b systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 09 18:55:31 volumio3-es9038b ntfs-3g[1554]: Unmounting /dev/sda1 (Volumio-16G-1) May 09 18:55:31 volumio3-es9038b systemd[1]: media-Volumio\x2d16G\x2d1.mount: Succeeded. May 09 18:55:31 volumio3-es9038b systemd[1]: volumio.service: Failed with result 'exit-code'. May 09 18:55:31 volumio3-es9038b systemd[1]: Started dynamicswap service. May 09 18:55:31 volumio3-es9038b systemd[1]: dynamicswap.service: Succeeded. May 09 18:55:31 volumio3-es9038b systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 09 18:55:31 volumio3-es9038b systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. May 09 18:55:31 volumio3-es9038b systemd[1]: Stopped Volumio Backend Module. May 09 18:55:31 volumio3-es9038b systemd[1]: Started Volumio Backend Module. May 09 18:55:31 volumio3-es9038b systemd[1]: Started dynamicswap service. May 09 18:55:31 volumio3-es9038b systemd[1]: dynamicswap.service: Succeeded. May 09 18:55:33 volumio3-es9038b volumio[1666]: info: ------------------------------------------- May 09 18:55:33 volumio3-es9038b volumio[1666]: info: ----- Volumio3 ---- May 09 18:55:33 volumio3-es9038b volumio[1666]: info: ------------------------------------------- May 09 18:55:33 volumio3-es9038b volumio[1666]: info: ----- System startup ---- May 09 18:55:33 volumio3-es9038b volumio[1666]: info: ------------------------------------------- May 09 18:55:34 volumio3-es9038b volumio[1666]: info: MYVOLUMIO Environment detected May 09 18:55:34 volumio3-es9038b volumio[1666]: info: Plugin folders cleanup May 09 18:55:34 volumio3-es9038b volumio[1666]: info: Scanning into folder /volumio/app/plugins/ May 09 18:55:34 volumio3-es9038b volumio[1666]: info: Scanning category audio_interface May 09 18:55:34 volumio3-es9038b volumio[1666]: info: Scanning category miscellanea May 09 18:55:34 volumio3-es9038b volumio[1666]: info: Scanning category music_service May 09 18:55:34 volumio3-es9038b volumio[1666]: info: Scanning category plugins.json May 09 18:55:34 volumio3-es9038b volumio[1666]: info: Scanning category system_controller May 09 18:55:34 volumio3-es9038b volumio[1666]: info: Scanning category user_interface May 09 18:55:34 volumio3-es9038b volumio[1666]: info: Scanning into folder /data/plugins/ May 09 18:55:34 volumio3-es9038b volumio[1666]: info: Scanning category audio_interface May 09 18:55:34 volumio3-es9038b volumio[1666]: info: Scanning category music_service May 09 18:55:34 volumio3-es9038b volumio[1666]: info: Plugin folders cleanup completed May 09 18:55:34 volumio3-es9038b volumio[1666]: info: ------------------------------------------- May 09 18:55:34 volumio3-es9038b volumio[1666]: info: ----- Core plugins startup ---- May 09 18:55:34 volumio3-es9038b volumio[1666]: info: ------------------------------------------- May 09 18:55:34 volumio3-es9038b volumio[1666]: info: Loading plugins from folder /volumio/app/plugins/ May 09 18:55:34 volumio3-es9038b volumio[1666]: info: Adding plugin upnp to MyMusic Plugins May 09 18:55:34 volumio3-es9038b volumio[1666]: info: Adding plugin airplay_emulation to MyMusic Plugins May 09 18:55:34 volumio3-es9038b volumio[1666]: info: Adding plugin upnp_browser to MyMusic Plugins May 09 18:55:34 volumio3-es9038b volumio[1666]: info: Loading plugins from folder /data/plugins/ May 09 18:55:34 volumio3-es9038b volumio[1666]: info: Loading plugin "system"... May 09 18:55:34 volumio3-es9038b volumio[1666]: info: Loading plugin "appearance"... May 09 18:55:35 volumio3-es9038b volumio[1666]: info: Loading plugin "network"... May 09 18:55:35 volumio3-es9038b volumio[1666]: info: Refreshing Cached IP Addresses May 09 18:55:35 volumio3-es9038b sudo[1693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 18:55:35 volumio3-es9038b sudo[1693]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:55:35 volumio3-es9038b sudo[1693]: pam_unix(sudo:session): session closed for user root May 09 18:55:35 volumio3-es9038b sudo[1695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 18:55:35 volumio3-es9038b volumio[1666]: info: Loading plugin "services"... May 09 18:55:35 volumio3-es9038b sudo[1695]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:55:35 volumio3-es9038b volumio[1666]: info: Loading plugin "alsa_controller"... May 09 18:55:35 volumio3-es9038b sudo[1695]: pam_unix(sudo:session): session closed for user root May 09 18:55:35 volumio3-es9038b sudo[1698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 09 18:55:35 volumio3-es9038b sudo[1698]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:55:35 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 18:55:35 volumio3-es9038b volumio[1666]: info: Loading plugin "wizard"... May 09 18:55:35 volumio3-es9038b volumio[1666]: info: Loading plugin "networkfs"... May 09 18:55:35 volumio3-es9038b volumio[1666]: info: Starting Udev Watcher for removable devices May 09 18:55:35 volumio3-es9038b volumio[1666]: info: Ignoring mount for partition: boot May 09 18:55:35 volumio3-es9038b volumio[1666]: info: Ignoring mount for partition: volumio May 09 18:55:35 volumio3-es9038b volumio[1666]: info: Ignoring mount for partition: volumio_data May 09 18:55:35 volumio3-es9038b volumio[1666]: info: Mounting Device Volumio-16G-1 May 09 18:55:35 volumio3-es9038b sudo[1707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Volumio-16G-1 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 09 18:55:35 volumio3-es9038b sudo[1707]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:55:35 volumio3-es9038b ntfs-3g[1711]: Version 2017.3.23AR.3 integrated FUSE 28 May 09 18:55:35 volumio3-es9038b ntfs-3g[1711]: Mounted /dev/sda1 (Read-Write, label "Volumio-16G-1", NTFS 3.1) May 09 18:55:35 volumio3-es9038b ntfs-3g[1711]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 May 09 18:55:35 volumio3-es9038b ntfs-3g[1711]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 May 09 18:55:35 volumio3-es9038b ntfs-3g[1711]: Global ownership and permissions enforced, configuration type 7 May 09 18:55:35 volumio3-es9038b sudo[1707]: pam_unix(sudo:session): session closed for user root May 09 18:55:35 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 18:55:35 volumio3-es9038b volumio[1666]: info: Loading plugin "volumio_command_line_client"... May 09 18:55:35 volumio3-es9038b volumio[1666]: info: Loading plugin "upnp"... May 09 18:55:35 volumio3-es9038b volumio[1666]: info: [1715248535975] Starting Upmpd Daemon May 09 18:55:35 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 18:55:35 volumio3-es9038b volumio[1666]: info: Loading plugin "my_music"... May 09 18:55:35 volumio3-es9038b volumio[1666]: info: Loading plugin "mpd"... May 09 18:55:36 volumio3-es9038b volumio[1666]: info: Loading plugin "upnp_browser"... May 09 18:55:36 volumio3-es9038b sudo[1698]: pam_unix(sudo:session): session closed for user root May 09 18:55:36 volumio3-es9038b volumio-remote-updater[565]: [2024-05-09 18:55:36] [connect] Successful connection May 09 18:55:36 volumio3-es9038b volumio[1666]: info: Loading plugin "alarm-clock"... May 09 18:55:36 volumio3-es9038b volumio[1666]: info: Loading plugin "airplay_emulation"... May 09 18:55:36 volumio3-es9038b volumio[1666]: info: Starting Shairport Sync May 09 18:55:36 volumio3-es9038b volumio[1666]: info: Loading plugin "last_100"... May 09 18:55:36 volumio3-es9038b volumio[1666]: info: Loading plugin "webradio"... May 09 18:55:36 volumio3-es9038b volumio[1666]: info: Loading plugin "i2s_dacs"... May 09 18:55:36 volumio3-es9038b volumio[1666]: info: Loading plugin "volumiodiscovery"... May 09 18:55:36 volumio3-es9038b volumio[1666]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 18:55:36 volumio3-es9038b volumio[1666]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 18:55:36 volumio3-es9038b volumio[1666]: *** WARNING *** For more information see May 09 18:55:36 volumio3-es9038b node[1666]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 18:55:36 volumio3-es9038b volumio[1666]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 18:55:36 volumio3-es9038b volumio[1666]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 18:55:36 volumio3-es9038b volumio[1666]: *** WARNING *** For more information see May 09 18:55:36 volumio3-es9038b node[1666]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 18:55:36 volumio3-es9038b node[1666]: *** WARNING *** For more information see May 09 18:55:36 volumio3-es9038b node[1666]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 18:55:36 volumio3-es9038b node[1666]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 18:55:36 volumio3-es9038b node[1666]: *** WARNING *** For more information see May 09 18:55:36 volumio3-es9038b volumio[1666]: info: Applying required configuration parameters for plugin volumiodiscovery May 09 18:55:37 volumio3-es9038b volumio[1666]: info: Discovery: Started advertising with name: Volumio3-ES9038B May 09 18:55:37 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 18:55:37 volumio3-es9038b volumio[1666]: info: Loading plugin "youtube2"... May 09 18:55:41 volumio3-es9038b volumio[1666]: info: Loading plugin "outputs"... May 09 18:55:41 volumio3-es9038b volumio[1666]: info: Loading plugin "albumart"... May 09 18:55:41 volumio3-es9038b volumio[1666]: info: Plugin example_plugin is not enabled May 09 18:55:41 volumio3-es9038b volumio[1666]: info: Loading plugin "inputs"... May 09 18:55:41 volumio3-es9038b volumio[1666]: info: Loading plugin "updater_comm"... May 09 18:55:41 volumio3-es9038b volumio[1666]: info: Plugin mpdemulation is not enabled May 09 18:55:41 volumio3-es9038b volumio[1666]: info: Loading plugin "rest_api"... May 09 18:55:41 volumio3-es9038b volumio[1666]: info: Loading plugin "websocket"... May 09 18:55:41 volumio3-es9038b volumio[1666]: info: Loading plugin "fusiondsp"... May 09 18:55:41 volumio3-es9038b volumio[1666]: Forking 3 albumart workers May 09 18:55:41 volumio3-es9038b volumio[1666]: info: Applying required configuration parameters for plugin fusiondsp May 09 18:55:41 volumio3-es9038b volumio[1666]: info: Loading plugin "lms"... May 09 18:55:42 volumio3-es9038b volumio[1666]: info: Loading plugin "personal_radio"... May 09 18:55:42 volumio3-es9038b volumio[1666]: info: ControllerPersonalRadio::constructor May 09 18:55:42 volumio3-es9038b volumio[1666]: info: Loading i18n strings for locale ja May 09 18:55:42 volumio3-es9038b volumio[1666]: Updating browse sources language May 09 18:55:42 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 18:55:42 volumio3-es9038b volumio[1666]: Starting albumart workers May 09 18:55:42 volumio3-es9038b volumio[1666]: Starting albumart workers May 09 18:55:42 volumio3-es9038b volumio[1666]: Starting albumart workers May 09 18:55:42 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 18:55:42 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::initPlayerControls May 09 18:55:42 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:55:42 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:55:42 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:55:42 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:55:42 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:55:42 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:55:42 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:55:42 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:55:42 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 18:55:42 volumio3-es9038b volumio[1666]: Express server listening on port 3000 May 09 18:55:42 volumio3-es9038b volumio[1666]: [Metrics] WebUI: 9s 849.65ms May 09 18:55:42 volumio3-es9038b volumio[1666]: info: CoreStateMachine::resetVolumioState May 09 18:55:42 volumio3-es9038b volumio[1666]: info: CoreStateMachine::getcurrentVolume May 09 18:55:42 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::volumioRetrievevolume May 09 18:55:43 volumio3-es9038b kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 09 18:55:43 volumio3-es9038b kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 May 09 18:55:43 volumio3-es9038b kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 09 18:55:43 volumio3-es9038b volumio[1666]: verbose: New Socket.io Connection to 192.168.2.113 from 192.168.2.107 UA: okhttp/3.12.12 Total Clients: 1 May 09 18:55:43 volumio3-es9038b volumio[1666]: verbose: New Socket.io Connection to 192.168.2.113 from 192.168.2.107 UA: okhttp/3.12.12 Total Clients: 2 May 09 18:55:43 volumio3-es9038b volumio[1666]: verbose: New Socket.io Connection to 192.168.2.113 from 192.168.2.107 UA: okhttp/3.12.12 Total Clients: 3 May 09 18:55:43 volumio3-es9038b volumio[1666]: verbose: New Socket.io Connection to 192.168.2.113 from 192.168.2.107 UA: okhttp/3.12.12 Total Clients: 4 May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreStateMachine::pushState May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CorePlayQueue::getTrack 0 May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::volumioPushState May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreStateMachine::updateTrackBlock May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CorePlayQueue::getTrackBlock May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::volumioRetrievevolume May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 18:55:43 volumio3-es9038b volumio[1666]: info: Reloading queue from file May 09 18:55:43 volumio3-es9038b volumio[1666]: verbose: New Socket.io Connection to 192.168.2.113 from 192.168.2.107 UA: okhttp/3.12.12 Total Clients: 5 May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreStateMachine::setRepeat false single undefined May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreStateMachine::pushState May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CorePlayQueue::getTrack 0 May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:55:43 volumio3-es9038b kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 09 18:55:43 volumio3-es9038b kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 May 09 18:55:43 volumio3-es9038b kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::volumioPushState May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreStateMachine::setRandom false May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreStateMachine::pushState May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CorePlayQueue::getTrack 0 May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::volumioPushState May 09 18:55:43 volumio3-es9038b volumio[1666]: info: Setting Device type: Raspberry PI May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreStateMachine::pushState May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CorePlayQueue::getTrack 0 May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::volumioPushState May 09 18:55:43 volumio3-es9038b volumio[1666]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 May 09 18:55:43 volumio3-es9038b volumio[1666]: info: Discovery: adding a32bd8ed-be21-46b8-af41-c2f55078b834 May 09 18:55:43 volumio3-es9038b volumio[1666]: info: Discovery: Found device Volumio3-ES9038B May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::volumioGetState May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CorePlayQueue::getTrack 0 May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::volumioGetVisibleSources May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::volumioGetState May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CorePlayQueue::getTrack 0 May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 09 18:55:43 volumio3-es9038b volumio[1666]: info: Received Get System Info May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 18:55:43 volumio3-es9038b volumio[1666]: info: Discovery: Getting this device information May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::volumioGetState May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CorePlayQueue::getTrack 0 May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::volumioGetState May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CorePlayQueue::getTrack 0 May 09 18:55:43 volumio3-es9038b volumio[1666]: info: Listing playlists May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::volumioGetQueue May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreStateMachine::getQueue May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CorePlayQueue::getQueue May 09 18:55:43 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 18:55:43 volumio3-es9038b volumio[1666]: info: effect true May 09 18:55:43 volumio3-es9038b volumio[1666]: info: Dsp mode set is EQ15 May 09 18:55:44 volumio3-es9038b volumio[1666]: info: << convolution filters enabled May 09 18:55:44 volumio3-es9038b volumio[1666]: info: tools file to play : May 09 18:55:44 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 18:55:44 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 18:55:44 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 18:55:44 volumio3-es9038b volumio[1666]: info: effect true May 09 18:55:44 volumio3-es9038b volumio[1666]: info: Dsp mode set is EQ15 May 09 18:55:44 volumio3-es9038b volumio[1666]: info: effect true May 09 18:55:44 volumio3-es9038b volumio[1666]: info: Dsp mode set is EQ15 May 09 18:55:44 volumio3-es9038b volumio[1666]: info: effect true May 09 18:55:44 volumio3-es9038b volumio[1666]: info: Dsp mode set is EQ15 May 09 18:55:44 volumio3-es9038b systemd[1]: supervisor.service: Service RestartSec=50s expired, scheduling restart. May 09 18:55:44 volumio3-es9038b systemd[1]: supervisor.service: Scheduled restart job, restart counter is at 2. May 09 18:55:44 volumio3-es9038b systemd[1]: Stopped Supervisor process control system for UNIX. May 09 18:55:44 volumio3-es9038b volumio[1666]: info: << convolution filters enabled May 09 18:55:44 volumio3-es9038b volumio[1666]: info: tools file to play : May 09 18:55:44 volumio3-es9038b systemd[1]: Started Supervisor process control system for UNIX. May 09 18:55:44 volumio3-es9038b volumio[1666]: info: << convolution filters enabled May 09 18:55:44 volumio3-es9038b volumio[1666]: info: tools file to play : May 09 18:55:44 volumio3-es9038b volumio[1666]: info: << convolution filters enabled May 09 18:55:44 volumio3-es9038b volumio[1666]: info: tools file to play : May 09 18:55:45 volumio3-es9038b supervisord[1786]: Error: The directory named as part of the path /var/log/supervisor/supervisord.log does not exist May 09 18:55:45 volumio3-es9038b supervisord[1786]: For help, use /usr/bin/supervisord -h May 09 18:55:45 volumio3-es9038b systemd[1]: supervisor.service: Main process exited, code=exited, status=2/INVALIDARGUMENT May 09 18:55:45 volumio3-es9038b systemd[1]: supervisor.service: Failed with result 'exit-code'. May 09 18:55:46 volumio3-es9038b sudo[1790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 18:55:46 volumio3-es9038b sudo[1790]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:55:46 volumio3-es9038b sudo[1790]: pam_unix(sudo:session): session closed for user root May 09 18:55:46 volumio3-es9038b sudo[1793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 18:55:46 volumio3-es9038b sudo[1793]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:55:46 volumio3-es9038b sudo[1793]: pam_unix(sudo:session): session closed for user root May 09 18:55:46 volumio3-es9038b sudo[1796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 09 18:55:46 volumio3-es9038b sudo[1796]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:55:46 volumio3-es9038b sudo[1796]: pam_unix(sudo:session): session closed for user root May 09 18:55:46 volumio3-es9038b volumio[1666]: info: Upmpdcli Daemon Started May 09 18:55:47 volumio3-es9038b volumio[1666]: verbose: New Socket.io Connection to 192.168.2.113 from 192.168.2.107 UA: okhttp/3.12.12 Total Clients: 6 May 09 18:55:47 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::volumioGetState May 09 18:55:47 volumio3-es9038b volumio[1666]: info: CorePlayQueue::getTrack 0 May 09 18:55:49 volumio3-es9038b volumio-remote-updater[565]: [2024-05-09 18:55:49] [connect] Successful connection May 09 18:55:49 volumio3-es9038b volumio-remote-updater[565]: [2024-05-09 18:55:49] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1715248549 101 May 09 18:55:49 volumio3-es9038b volumio[1666]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 7 May 09 18:55:50 volumio3-es9038b volumio[1666]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 09 18:55:50 volumio3-es9038b volumio[1666]: info: CURURI: albums:// May 09 18:55:50 volumio3-es9038b volumio[1666]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 18:55:50 volumio3-es9038b volumio[1666]: TypeError: Cannot read property 'sendCommand' of undefined May 09 18:55:50 volumio3-es9038b volumio[1666]: at /volumio/app/plugins/music_service/mpd/index.js:2986:22 May 09 18:55:50 volumio3-es9038b volumio[1666]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 09 18:55:50 volumio3-es9038b volumio[1666]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 18:55:51 volumio3-es9038b sudo[1807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-09 18:54 May 09 18:55:51 volumio3-es9038b sudo[1807]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:55:51 volumio3-es9038b sudo[1807]: pam_unix(sudo:session): session closed for user root May 09 18:55:51 volumio3-es9038b volumio-remote-updater[565]: [2024-05-09 18:55:51] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 09 18:55:51 volumio3-es9038b volumio-remote-updater[565]: [2024-05-09 18:55:51] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 09 18:55:51 volumio3-es9038b systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 09 18:55:51 volumio3-es9038b ntfs-3g[1711]: Unmounting /dev/sda1 (Volumio-16G-1) May 09 18:55:51 volumio3-es9038b systemd[1]: media-Volumio\x2d16G\x2d1.mount: Succeeded. May 09 18:55:51 volumio3-es9038b systemd[1]: volumio.service: Failed with result 'exit-code'. May 09 18:55:51 volumio3-es9038b systemd[1]: Started dynamicswap service. May 09 18:55:51 volumio3-es9038b systemd[1]: dynamicswap.service: Succeeded. May 09 18:55:51 volumio3-es9038b systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 09 18:55:51 volumio3-es9038b systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. May 09 18:55:51 volumio3-es9038b systemd[1]: Stopped Volumio Backend Module. May 09 18:55:51 volumio3-es9038b systemd[1]: Started Volumio Backend Module. May 09 18:55:51 volumio3-es9038b systemd[1]: Started dynamicswap service. May 09 18:55:51 volumio3-es9038b systemd[1]: dynamicswap.service: Succeeded. May 09 18:55:53 volumio3-es9038b volumio[1819]: info: ------------------------------------------- May 09 18:55:53 volumio3-es9038b volumio[1819]: info: ----- Volumio3 ---- May 09 18:55:53 volumio3-es9038b volumio[1819]: info: ------------------------------------------- May 09 18:55:53 volumio3-es9038b volumio[1819]: info: ----- System startup ---- May 09 18:55:53 volumio3-es9038b volumio[1819]: info: ------------------------------------------- May 09 18:55:54 volumio3-es9038b volumio[1819]: info: MYVOLUMIO Environment detected May 09 18:55:54 volumio3-es9038b volumio[1819]: info: Plugin folders cleanup May 09 18:55:54 volumio3-es9038b volumio[1819]: info: Scanning into folder /volumio/app/plugins/ May 09 18:55:54 volumio3-es9038b volumio[1819]: info: Scanning category audio_interface May 09 18:55:54 volumio3-es9038b volumio[1819]: info: Scanning category miscellanea May 09 18:55:54 volumio3-es9038b volumio[1819]: info: Scanning category music_service May 09 18:55:54 volumio3-es9038b volumio[1819]: info: Scanning category plugins.json May 09 18:55:54 volumio3-es9038b volumio[1819]: info: Scanning category system_controller May 09 18:55:54 volumio3-es9038b volumio[1819]: info: Scanning category user_interface May 09 18:55:54 volumio3-es9038b volumio[1819]: info: Scanning into folder /data/plugins/ May 09 18:55:54 volumio3-es9038b volumio[1819]: info: Scanning category audio_interface May 09 18:55:54 volumio3-es9038b volumio[1819]: info: Scanning category music_service May 09 18:55:54 volumio3-es9038b volumio[1819]: info: Plugin folders cleanup completed May 09 18:55:54 volumio3-es9038b volumio[1819]: info: ------------------------------------------- May 09 18:55:54 volumio3-es9038b volumio[1819]: info: ----- Core plugins startup ---- May 09 18:55:54 volumio3-es9038b volumio[1819]: info: ------------------------------------------- May 09 18:55:54 volumio3-es9038b volumio[1819]: info: Loading plugins from folder /volumio/app/plugins/ May 09 18:55:54 volumio3-es9038b volumio[1819]: info: Adding plugin upnp to MyMusic Plugins May 09 18:55:54 volumio3-es9038b volumio[1819]: info: Adding plugin airplay_emulation to MyMusic Plugins May 09 18:55:54 volumio3-es9038b volumio[1819]: info: Adding plugin upnp_browser to MyMusic Plugins May 09 18:55:54 volumio3-es9038b volumio[1819]: info: Loading plugins from folder /data/plugins/ May 09 18:55:54 volumio3-es9038b volumio[1819]: info: Loading plugin "system"... May 09 18:55:54 volumio3-es9038b volumio[1819]: info: Loading plugin "appearance"... May 09 18:55:55 volumio3-es9038b volumio[1819]: info: Loading plugin "network"... May 09 18:55:55 volumio3-es9038b volumio[1819]: info: Refreshing Cached IP Addresses May 09 18:55:55 volumio3-es9038b sudo[1847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 18:55:55 volumio3-es9038b sudo[1847]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:55:55 volumio3-es9038b sudo[1847]: pam_unix(sudo:session): session closed for user root May 09 18:55:55 volumio3-es9038b sudo[1849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 18:55:55 volumio3-es9038b sudo[1849]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:55:55 volumio3-es9038b volumio[1819]: info: Loading plugin "services"... May 09 18:55:55 volumio3-es9038b volumio[1819]: info: Loading plugin "alsa_controller"... May 09 18:55:55 volumio3-es9038b sudo[1849]: pam_unix(sudo:session): session closed for user root May 09 18:55:55 volumio3-es9038b sudo[1852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 09 18:55:55 volumio3-es9038b sudo[1852]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:55:55 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 18:55:55 volumio3-es9038b volumio[1819]: info: Loading plugin "wizard"... May 09 18:55:55 volumio3-es9038b volumio[1819]: info: Loading plugin "networkfs"... May 09 18:55:55 volumio3-es9038b volumio[1819]: info: Starting Udev Watcher for removable devices May 09 18:55:55 volumio3-es9038b volumio[1819]: info: Ignoring mount for partition: boot May 09 18:55:55 volumio3-es9038b volumio[1819]: info: Ignoring mount for partition: volumio May 09 18:55:55 volumio3-es9038b volumio[1819]: info: Ignoring mount for partition: volumio_data May 09 18:55:55 volumio3-es9038b volumio[1819]: info: Mounting Device Volumio-16G-1 May 09 18:55:55 volumio3-es9038b sudo[1861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Volumio-16G-1 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 09 18:55:55 volumio3-es9038b sudo[1861]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:55:55 volumio3-es9038b ntfs-3g[1864]: Version 2017.3.23AR.3 integrated FUSE 28 May 09 18:55:55 volumio3-es9038b ntfs-3g[1864]: Mounted /dev/sda1 (Read-Write, label "Volumio-16G-1", NTFS 3.1) May 09 18:55:55 volumio3-es9038b ntfs-3g[1864]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 May 09 18:55:55 volumio3-es9038b ntfs-3g[1864]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 May 09 18:55:55 volumio3-es9038b ntfs-3g[1864]: Global ownership and permissions enforced, configuration type 7 May 09 18:55:55 volumio3-es9038b sudo[1861]: pam_unix(sudo:session): session closed for user root May 09 18:55:55 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 18:55:55 volumio3-es9038b volumio[1819]: info: Loading plugin "volumio_command_line_client"... May 09 18:55:56 volumio3-es9038b volumio[1819]: info: Loading plugin "upnp"... May 09 18:55:56 volumio3-es9038b volumio[1819]: info: [1715248556008] Starting Upmpd Daemon May 09 18:55:56 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 18:55:56 volumio3-es9038b volumio[1819]: info: Loading plugin "my_music"... May 09 18:55:56 volumio3-es9038b volumio[1819]: info: Loading plugin "mpd"... May 09 18:55:56 volumio3-es9038b volumio[1819]: info: Loading plugin "upnp_browser"... May 09 18:55:56 volumio3-es9038b volumio-remote-updater[565]: [2024-05-09 18:55:56] [connect] Successful connection May 09 18:55:56 volumio3-es9038b sudo[1852]: pam_unix(sudo:session): session closed for user root May 09 18:55:56 volumio3-es9038b volumio[1819]: info: Loading plugin "alarm-clock"... May 09 18:55:56 volumio3-es9038b volumio[1819]: info: Loading plugin "airplay_emulation"... May 09 18:55:56 volumio3-es9038b volumio[1819]: info: Starting Shairport Sync May 09 18:55:56 volumio3-es9038b volumio[1819]: info: Loading plugin "last_100"... May 09 18:55:56 volumio3-es9038b volumio[1819]: info: Loading plugin "webradio"... May 09 18:55:56 volumio3-es9038b volumio[1819]: info: Loading plugin "i2s_dacs"... May 09 18:55:56 volumio3-es9038b volumio[1819]: info: Loading plugin "volumiodiscovery"... May 09 18:55:57 volumio3-es9038b volumio[1819]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 18:55:57 volumio3-es9038b node[1819]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 18:55:57 volumio3-es9038b volumio[1819]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 18:55:57 volumio3-es9038b node[1819]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 18:55:57 volumio3-es9038b volumio[1819]: *** WARNING *** For more information see May 09 18:55:57 volumio3-es9038b node[1819]: *** WARNING *** For more information see May 09 18:55:57 volumio3-es9038b volumio[1819]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 18:55:57 volumio3-es9038b node[1819]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 18:55:57 volumio3-es9038b volumio[1819]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 18:55:57 volumio3-es9038b node[1819]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 18:55:57 volumio3-es9038b volumio[1819]: *** WARNING *** For more information see May 09 18:55:57 volumio3-es9038b node[1819]: *** WARNING *** For more information see May 09 18:55:57 volumio3-es9038b volumio[1819]: info: Applying required configuration parameters for plugin volumiodiscovery May 09 18:55:57 volumio3-es9038b volumio[1819]: info: Discovery: Started advertising with name: Volumio3-ES9038B May 09 18:55:57 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 18:55:57 volumio3-es9038b volumio[1819]: info: Loading plugin "youtube2"... May 09 18:56:01 volumio3-es9038b volumio[1819]: info: Loading plugin "outputs"... May 09 18:56:01 volumio3-es9038b volumio[1819]: info: Loading plugin "albumart"... May 09 18:56:01 volumio3-es9038b volumio[1819]: info: Plugin example_plugin is not enabled May 09 18:56:01 volumio3-es9038b volumio[1819]: info: Loading plugin "inputs"... May 09 18:56:01 volumio3-es9038b volumio[1819]: info: Loading plugin "updater_comm"... May 09 18:56:01 volumio3-es9038b volumio[1819]: info: Plugin mpdemulation is not enabled May 09 18:56:01 volumio3-es9038b volumio[1819]: info: Loading plugin "rest_api"... May 09 18:56:01 volumio3-es9038b volumio[1819]: info: Loading plugin "websocket"... May 09 18:56:01 volumio3-es9038b volumio[1819]: info: Loading plugin "fusiondsp"... May 09 18:56:01 volumio3-es9038b volumio[1819]: Forking 3 albumart workers May 09 18:56:01 volumio3-es9038b volumio[1819]: info: Applying required configuration parameters for plugin fusiondsp May 09 18:56:02 volumio3-es9038b volumio[1819]: info: Loading plugin "lms"... May 09 18:56:02 volumio3-es9038b volumio[1819]: info: Loading plugin "personal_radio"... May 09 18:56:02 volumio3-es9038b volumio[1819]: info: ControllerPersonalRadio::constructor May 09 18:56:02 volumio3-es9038b volumio[1819]: info: Loading i18n strings for locale ja May 09 18:56:02 volumio3-es9038b volumio[1819]: Updating browse sources language May 09 18:56:02 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 18:56:02 volumio3-es9038b volumio[1819]: Starting albumart workers May 09 18:56:02 volumio3-es9038b volumio[1819]: Starting albumart workers May 09 18:56:02 volumio3-es9038b volumio[1819]: Starting albumart workers May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::initPlayerControls May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 18:56:03 volumio3-es9038b volumio[1819]: Express server listening on port 3000 May 09 18:56:03 volumio3-es9038b volumio[1819]: [Metrics] WebUI: 9s 952.07ms May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreStateMachine::resetVolumioState May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreStateMachine::getcurrentVolume May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::volumioRetrievevolume May 09 18:56:03 volumio3-es9038b kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 09 18:56:03 volumio3-es9038b kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 May 09 18:56:03 volumio3-es9038b kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 09 18:56:03 volumio3-es9038b volumio[1819]: verbose: New Socket.io Connection to 192.168.2.113 from 192.168.2.107 UA: okhttp/3.12.12 Total Clients: 1 May 09 18:56:03 volumio3-es9038b volumio[1819]: verbose: New Socket.io Connection to 192.168.2.113 from 192.168.2.107 UA: okhttp/3.12.12 Total Clients: 2 May 09 18:56:03 volumio3-es9038b volumio[1819]: verbose: New Socket.io Connection to 192.168.2.113 from 192.168.2.107 UA: okhttp/3.12.12 Total Clients: 3 May 09 18:56:03 volumio3-es9038b volumio[1819]: verbose: New Socket.io Connection to 192.168.2.113 from 192.168.2.107 UA: okhttp/3.12.12 Total Clients: 4 May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreStateMachine::pushState May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CorePlayQueue::getTrack 0 May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::volumioPushState May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreStateMachine::updateTrackBlock May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CorePlayQueue::getTrackBlock May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::volumioRetrievevolume May 09 18:56:03 volumio3-es9038b kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 09 18:56:03 volumio3-es9038b kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 May 09 18:56:03 volumio3-es9038b kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 18:56:03 volumio3-es9038b volumio[1819]: info: Reloading queue from file May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreStateMachine::pushState May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CorePlayQueue::getTrack 0 May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::volumioPushState May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreStateMachine::setRepeat false single undefined May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreStateMachine::pushState May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CorePlayQueue::getTrack 0 May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::volumioPushState May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreStateMachine::setRandom false May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreStateMachine::pushState May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CorePlayQueue::getTrack 0 May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::volumioPushState May 09 18:56:03 volumio3-es9038b volumio[1819]: info: Setting Device type: Raspberry PI May 09 18:56:03 volumio3-es9038b volumio[1819]: info: Discovery: adding a32bd8ed-be21-46b8-af41-c2f55078b834 May 09 18:56:03 volumio3-es9038b volumio[1819]: info: Discovery: Found device Volumio3-ES9038B May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::volumioGetState May 09 18:56:03 volumio3-es9038b volumio[1819]: info: CorePlayQueue::getTrack 0 May 09 18:56:04 volumio3-es9038b volumio[1819]: verbose: New Socket.io Connection to 192.168.2.113 from 192.168.2.107 UA: okhttp/3.12.12 Total Clients: 5 May 09 18:56:04 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 09 18:56:05 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 09 18:56:05 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::volumioGetVisibleSources May 09 18:56:05 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 18:56:05 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::volumioGetState May 09 18:56:05 volumio3-es9038b volumio[1819]: info: CorePlayQueue::getTrack 0 May 09 18:56:05 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 09 18:56:05 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 09 18:56:05 volumio3-es9038b volumio[1819]: info: Received Get System Info May 09 18:56:05 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 18:56:05 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 18:56:05 volumio3-es9038b volumio[1819]: info: Discovery: Getting this device information May 09 18:56:05 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::volumioGetState May 09 18:56:05 volumio3-es9038b volumio[1819]: info: CorePlayQueue::getTrack 0 May 09 18:56:05 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 18:56:05 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::volumioGetState May 09 18:56:05 volumio3-es9038b volumio[1819]: info: CorePlayQueue::getTrack 0 May 09 18:56:05 volumio3-es9038b volumio[1819]: info: Listing playlists May 09 18:56:05 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::volumioGetQueue May 09 18:56:05 volumio3-es9038b volumio[1819]: info: CoreStateMachine::getQueue May 09 18:56:05 volumio3-es9038b volumio[1819]: info: CorePlayQueue::getQueue May 09 18:56:05 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 18:56:05 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 18:56:05 volumio3-es9038b volumio[1819]: info: effect true May 09 18:56:05 volumio3-es9038b volumio[1819]: info: Dsp mode set is EQ15 May 09 18:56:05 volumio3-es9038b volumio[1819]: info: effect true May 09 18:56:05 volumio3-es9038b volumio[1819]: info: Dsp mode set is EQ15 May 09 18:56:05 volumio3-es9038b volumio[1819]: info: << convolution filters enabled May 09 18:56:05 volumio3-es9038b volumio[1819]: info: tools file to play : May 09 18:56:05 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 18:56:05 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 18:56:05 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 18:56:05 volumio3-es9038b volumio[1819]: info: << convolution filters enabled May 09 18:56:05 volumio3-es9038b volumio[1819]: info: tools file to play : May 09 18:56:05 volumio3-es9038b volumio[1819]: info: effect true May 09 18:56:05 volumio3-es9038b volumio[1819]: info: Dsp mode set is EQ15 May 09 18:56:05 volumio3-es9038b volumio[1819]: info: effect true May 09 18:56:05 volumio3-es9038b volumio[1819]: info: Dsp mode set is EQ15 May 09 18:56:06 volumio3-es9038b volumio[1819]: info: effect true May 09 18:56:06 volumio3-es9038b volumio[1819]: info: Dsp mode set is EQ15 May 09 18:56:06 volumio3-es9038b sudo[1985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 18:56:06 volumio3-es9038b sudo[1985]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:56:06 volumio3-es9038b volumio[1819]: info: << convolution filters enabled May 09 18:56:06 volumio3-es9038b volumio[1819]: info: tools file to play : May 09 18:56:06 volumio3-es9038b sudo[1985]: pam_unix(sudo:session): session closed for user root May 09 18:56:06 volumio3-es9038b sudo[1988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 18:56:06 volumio3-es9038b sudo[1988]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:56:06 volumio3-es9038b sudo[1988]: pam_unix(sudo:session): session closed for user root May 09 18:56:06 volumio3-es9038b volumio[1819]: info: << convolution filters enabled May 09 18:56:06 volumio3-es9038b volumio[1819]: info: tools file to play : May 09 18:56:06 volumio3-es9038b volumio[1819]: info: << convolution filters enabled May 09 18:56:06 volumio3-es9038b volumio[1819]: info: tools file to play : May 09 18:56:06 volumio3-es9038b sudo[1991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 09 18:56:06 volumio3-es9038b sudo[1991]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 18:56:06 volumio3-es9038b sudo[1991]: pam_unix(sudo:session): session closed for user root May 09 18:56:06 volumio3-es9038b volumio[1819]: info: Upmpdcli Daemon Started May 09 18:56:07 volumio3-es9038b volumio[1819]: verbose: New Socket.io Connection to 192.168.2.113 from 192.168.2.107 UA: okhttp/3.12.12 Total Clients: 6 May 09 18:56:07 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::volumioGetState May 09 18:56:07 volumio3-es9038b volumio[1819]: info: CorePlayQueue::getTrack 0 May 09 18:56:08 volumio3-es9038b volumio-remote-updater[565]: [2024-05-09 18:56:08] [connect] Successful connection May 09 18:56:08 volumio3-es9038b volumio-remote-updater[565]: [2024-05-09 18:56:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1715248568 101 May 09 18:56:08 volumio3-es9038b volumio[1819]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 7 May 09 18:56:16 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 18:56:16 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 09 18:56:16 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 09 18:56:16 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 09 18:56:16 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 09 18:56:16 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 18:56:16 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 18:56:16 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 18:56:16 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 18:56:16 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::volumioGetBrowseSources May 09 18:56:16 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::volumioGetBrowseSources May 09 18:56:16 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::volumioGetBrowseSources May 09 18:56:16 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 09 18:56:17 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 09 18:56:19 volumio3-es9038b volumio[1819]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 09 18:56:19 volumio3-es9038b volumio[1819]: verbose: ControllerMpd::sendMpdCommand rescan May 09 18:56:19 volumio3-es9038b volumio[1819]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 18:56:19 volumio3-es9038b volumio[1819]: TypeError: Cannot read property 'then' of undefined May 09 18:56:19 volumio3-es9038b volumio[1819]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:331:6) May 09 18:56:19 volumio3-es9038b volumio[1819]: at ControllerMpd.rescanDb (/volumio/app/plugins/music_service/mpd/index.js:1885:15) May 09 18:56:19 volumio3-es9038b volumio[1819]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32) May 09 18:56:19 volumio3-es9038b volumio[1819]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1045:26) May 09 18:56:19 volumio3-es9038b volumio[1819]: at Socket.emit (events.js:315:20) May 09 18:56:19 volumio3-es9038b volumio[1819]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 09 18:56:19 volumio3-es9038b volumio[1819]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 09 18:56:19 volumio3-es9038b volumio[1819]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 18:56:20 volumio3-es9038b sudo[2003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-09 18:55 May 09 18:56:20 volumio3-es9038b sudo[2003]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"