-- Logs begin at Mon 2025-04-28 18:01:35 HKT, end at Mon 2025-04-28 18:10:36 HKT. -- Apr 28 18:09:01 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 18:09:05 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 18:09:09 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 18:09:13 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 18:09:16 volumio ntpd[2968]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 28 18:09:17 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 18:09:18 volumio kernel: usb 1-1.4: new low-speed USB device number 6 using ehci-pci Apr 28 18:09:18 volumio kernel: usb 1-1.4: New USB device found, idVendor=046d, idProduct=c31c, bcdDevice=64.00 Apr 28 18:09:18 volumio kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Apr 28 18:09:18 volumio kernel: usb 1-1.4: Product: USB Keyboard Apr 28 18:09:18 volumio kernel: usb 1-1.4: Manufacturer: Logitech Apr 28 18:09:18 volumio kernel: input: Logitech USB Keyboard as /devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1.4/1-1.4:1.0/0003:046D:C31C.0007/input/input18 Apr 28 18:09:18 volumio kernel: hid-generic 0003:046D:C31C.0007: input,hidraw1: USB HID v1.10 Keyboard [Logitech USB Keyboard] on usb-0000:00:1d.7-1.4/input0 Apr 28 18:09:18 volumio kernel: input: Logitech USB Keyboard Consumer Control as /devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1.4/1-1.4:1.1/0003:046D:C31C.0008/input/input19 Apr 28 18:09:18 volumio kernel: input: Logitech USB Keyboard System Control as /devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1.4/1-1.4:1.1/0003:046D:C31C.0008/input/input20 Apr 28 18:09:18 volumio kernel: hid-generic 0003:046D:C31C.0008: input,hidraw2: USB HID v1.10 Device [Logitech USB Keyboard] on usb-0000:00:1d.7-1.4/input1 Apr 28 18:09:18 volumio systemd-udevd[4152]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 28 18:09:18 volumio systemd-logind[819]: Watching system buttons on /dev/input/event7 (Logitech USB Keyboard System Control) Apr 28 18:09:18 volumio systemd-udevd[4148]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 28 18:09:18 volumio systemd-logind[819]: Watching system buttons on /dev/input/event5 (Logitech USB Keyboard) Apr 28 18:09:18 volumio systemd-udevd[4149]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 28 18:09:21 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 18:09:25 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 18:09:29 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 18:09:33 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 18:09:37 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 18:09:41 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 18:09:45 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 18:09:49 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 18:09:53 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 18:09:54 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Apr 28 18:09:54 volumio volumio[1106]: info: Adding a new share Apr 28 18:09:54 volumio volumio[1106]: info: No correspondence found in configuration for share music on IP 192.168.31.49 Apr 28 18:09:54 volumio sudo[4276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=depu,password=Pese8888,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.31.49/music /mnt/NAS/music Apr 28 18:09:54 volumio sudo[4276]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 18:09:54 volumio kernel: FS-Cache: Loaded Apr 28 18:09:54 volumio kernel: Key type dns_resolver registered Apr 28 18:09:54 volumio kernel: Key type cifs.spnego registered Apr 28 18:09:54 volumio kernel: Key type cifs.idmap registered Apr 28 18:09:54 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Apr 28 18:09:54 volumio kernel: CIFS: Attempting to mount //192.168.31.49/music Apr 28 18:09:55 volumio sudo[4276]: pam_unix(sudo:session): session closed for user root Apr 28 18:09:55 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand status Apr 28 18:09:55 volumio volumio[1106]: info: Apr 28 18:09:55 volumio volumio[1106]: ---------------------------- MPD announces state update: update Apr 28 18:09:55 volumio volumio[1106]: info: ControllerMpd::getState Apr 28 18:09:55 volumio volumio[1106]: verbose: ControllerMpd::sendMpdCommand status Apr 28 18:09:55 volumio volumio[1106]: info: sendMpdCommand status took 3 milliseconds Apr 28 18:09:55 volumio volumio[1106]: info: sendMpdCommand status took 1 milliseconds Apr 28 18:09:55 volumio volumio[1106]: verbose: ControllerMpd::parseState Apr 28 18:09:55 volumio volumio[1106]: info: Command Router : Notfying DB Updatetrue Apr 28 18:09:55 volumio volumio[1106]: verbose: ControllerMpd::parseState Apr 28 18:09:55 volumio volumio[1106]: info: ControllerMpd::pushState Apr 28 18:09:55 volumio volumio[1106]: info: CoreCommandRouter::servicePushState Apr 28 18:09:55 volumio volumio[1106]: info: CoreStateMachine::pushState Apr 28 18:09:55 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Apr 28 18:09:55 volumio volumio[1106]: info: CoreCommandRouter::volumioPushState Apr 28 18:09:55 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Apr 28 18:09:55 volumio volumio[1106]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 28 18:09:55 volumio volumio[1106]: verbose: CURRENT POSITION 0 Apr 28 18:09:55 volumio volumio[1106]: info: CoreStateMachine::syncState stateService stop Apr 28 18:09:55 volumio volumio[1106]: info: CoreStateMachine::syncState currentStatus stop Apr 28 18:09:55 volumio volumio[1106]: info: CoreStateMachine::pushState Apr 28 18:09:55 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Apr 28 18:09:55 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 18:09:55 volumio volumio[1106]: info: CoreCommandRouter::volumioPushState Apr 28 18:09:55 volumio volumio[1106]: info: No code Apr 28 18:09:55 volumio volumio[1106]: info: CoreStateMachine::pushState Apr 28 18:09:55 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Apr 28 18:09:55 volumio volumio[1106]: info: CoreCommandRouter::volumioPushState Apr 28 18:09:55 volumio volumio[1106]: info: ------------------------------ 31ms Apr 28 18:09:55 volumio volumio[1106]: info: [1745834995195] Database update started Apr 28 18:09:56 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 28 18:09:57 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 18:09:58 volumio volumio[1106]: info: CoreCommandRouter::Close All Modals sent Apr 28 18:10:01 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 18:10:04 volumio volumio[1106]: info: CoreCommandRouter::volumioGetState Apr 28 18:10:04 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Apr 28 18:10:10 volumio volumio[1106]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 28 18:10:10 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 28 18:10:10 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Apr 28 18:10:10 volumio sudo[4345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Apr 28 18:10:10 volumio sudo[4345]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 18:10:10 volumio sudo[4345]: pam_unix(sudo:session): session closed for user root Apr 28 18:10:10 volumio sudo[4351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 28 18:10:10 volumio sudo[4351]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 18:10:10 volumio sudo[4351]: pam_unix(sudo:session): session closed for user root Apr 28 18:10:10 volumio sudo[4360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 28 18:10:10 volumio sudo[4360]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 18:10:10 volumio sudo[4360]: pam_unix(sudo:session): session closed for user root Apr 28 18:10:10 volumio sudo[4365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 28 18:10:10 volumio sudo[4365]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 18:10:10 volumio sudo[4365]: pam_unix(sudo:session): session closed for user root Apr 28 18:10:11 volumio sudo[4371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 28 18:10:11 volumio sudo[4371]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 18:10:11 volumio sudo[4371]: pam_unix(sudo:session): session closed for user root Apr 28 18:10:11 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 28 18:10:11 volumio sudo[4374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 28 18:10:11 volumio sudo[4374]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 18:10:11 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 28 18:10:11 volumio sudo[4374]: pam_unix(sudo:session): session closed for user root Apr 28 18:10:11 volumio sudo[4377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 28 18:10:11 volumio sudo[4377]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 18:10:15 volumio volumio[1106]: info: CoreCommandRouter::volumioGetState Apr 28 18:10:15 volumio volumio[1106]: info: CorePlayQueue::getTrack 0 Apr 28 18:10:17 volumio volumio[1106]: info: CoreCommandRouter::volumioGetQueue Apr 28 18:10:17 volumio volumio[1106]: info: CoreStateMachine::getQueue Apr 28 18:10:17 volumio volumio[1106]: info: CorePlayQueue::getQueue Apr 28 18:10:18 volumio sudo[4377]: pam_unix(sudo:session): session closed for user root Apr 28 18:10:24 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Apr 28 18:10:24 volumio volumio[1106]: Error Retrieving last played file: Error: /data/laststates.json: ENOENT: no such file or directory, open '/data/laststates.json' Apr 28 18:10:24 volumio volumio[1106]: info: Preload queue cleared Apr 28 18:10:30 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 28 18:10:30 volumio volumio[1106]: info: Preload queue cleared Apr 28 18:10:31 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 28 18:10:31 volumio volumio[1106]: info: Preload queue cleared Apr 28 18:10:33 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 28 18:10:33 volumio volumio[1106]: info: Preload queue cleared Apr 28 18:10:36 volumio volumio[1106]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 28 18:10:36 volumio volumio[1106]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 18:10:36 volumio volumio[1106]: TypeError: Cannot read property 'length' of undefined Apr 28 18:10:36 volumio volumio[1106]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Apr 28 18:10:36 volumio volumio[1106]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Apr 28 18:10:36 volumio volumio[1106]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Apr 28 18:10:36 volumio volumio[1106]: at Parser.emit (events.js:400:28) Apr 28 18:10:36 volumio volumio[1106]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Apr 28 18:10:36 volumio volumio[1106]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Apr 28 18:10:36 volumio volumio[1106]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Apr 28 18:10:36 volumio volumio[1106]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Apr 28 18:10:36 volumio volumio[1106]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Apr 28 18:10:36 volumio volumio[1106]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Apr 28 18:10:36 volumio volumio[1106]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Apr 28 18:10:36 volumio volumio[1106]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Apr 28 18:10:36 volumio volumio[1106]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Apr 28 18:10:36 volumio volumio[1106]: at IncomingMessage.emit (events.js:412:35) Apr 28 18:10:36 volumio volumio[1106]: at endReadableNT (internal/streams/readable.js:1333:12) Apr 28 18:10:36 volumio volumio[1106]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Apr 28 18:10:36 volumio volumio[1106]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 18:10:36 volumio sudo[4441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-28 18:09 Apr 28 18:10:36 volumio sudo[4441]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:27:48 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4bc1ecb08f34ad14b420d6678a2aef1b"