-- Logs begin at Sun 2025-06-15 07:05:01 +14, end at Sun 2025-06-15 07:07:03 +14. -- Jun 15 07:06:03 volumio systemd-udevd[255]: sr0: Worker [287] processing SEQNUM=2109 is taking a long time Jun 15 07:06:04 volumio volumio[600]: error: MyVolumio Plugin failed to start in a timely fashion Jun 15 07:06:04 volumio volumio[600]: info: BOOT COMPLETED Jun 15 07:06:04 volumio volumio[600]: [Metrics] CommandRouter: 57s 593.20ms Jun 15 07:06:04 volumio volumio[600]: info: CoreCommandRouter::volumiosetStartupVolume Jun 15 07:06:04 volumio volumio[600]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 07:06:04 volumio volumio[600]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 07:06:04 volumio volumio[600]: info: CoreCommandRouter::Close All Modals sent Jun 15 07:06:04 volumio volumio[600]: info: CoreCommandRouter::Close All Modals sent Jun 15 07:06:05 volumio kernel: usb 1-3: reset high-speed USB device number 3 using xhci_hcd Jun 15 07:06:05 volumio volumio[600]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 15 07:06:05 volumio volumio[600]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 15 07:06:05 volumio volumio[600]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 15 07:06:09 volumio volumio[600]: info: CoreCommandRouter::volumioGetState Jun 15 07:06:09 volumio volumio[600]: info: CorePlayQueue::getTrack 0 Jun 15 07:06:18 volumio volumio[600]: info: CoreCommandRouter::volumioGetQueue Jun 15 07:06:18 volumio volumio[600]: info: CoreStateMachine::getQueue Jun 15 07:06:18 volumio volumio[600]: info: CorePlayQueue::getQueue Jun 15 07:06:20 volumio volumio[600]: info: CoreCommandRouter::volumioGetState Jun 15 07:06:20 volumio volumio[600]: info: CorePlayQueue::getTrack 0 Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 15 07:06:28 volumio volumio[600]: info: Adding plugin bluetooth to MyMusic Plugins Jun 15 07:06:28 volumio volumio[600]: info: Adding plugin multiroom to MyMusic Plugins Jun 15 07:06:28 volumio volumio[600]: info: Adding plugin metavolumio to MyMusic Plugins Jun 15 07:06:28 volumio volumio[600]: info: Adding plugin cd_controller to MyMusic Plugins Jun 15 07:06:28 volumio volumio[600]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 15 07:06:28 volumio volumio[600]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 15 07:06:28 volumio volumio[600]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 15 07:06:29 volumio volumio[600]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 15 07:06:29 volumio volumio[600]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 15 07:06:29 volumio volumio[600]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 07:06:29 volumio volumio[600]: info: Starting MyVolumio Remote Streaming Endpoints Jun 15 07:06:29 volumio volumio[600]: info: MyVolumio not started Jun 15 07:06:29 volumio volumio[600]: info: Initializing device activation check Jun 15 07:06:29 volumio volumio[600]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 15 07:06:29 volumio volumio[600]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 15 07:06:30 volumio volumio[600]: info: Starting Streaming Service Transparent Proxy Jun 15 07:06:30 volumio volumio[600]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 15 07:06:30 volumio volumio[600]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 15 07:06:30 volumio volumio[600]: info: Streaming services startup Jun 15 07:06:30 volumio volumio[600]: info: Starting Streaming Daemon Jun 15 07:06:30 volumio sudo[1421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 15 07:06:30 volumio sudo[1421]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 07:06:30 volumio volumio[600]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 15 07:06:30 volumio sudo[1421]: pam_unix(sudo:session): session closed for user root Jun 15 07:06:30 volumio volumio[600]: error: Cannot start Volumio Streaming Daemon Jun 15 07:06:30 volumio volumio[600]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 15 07:06:30 volumio volumio[600]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 15 07:06:31 volumio volumio[600]: STREAMING PROXY: Starting server on port 3245 Jun 15 07:06:31 volumio volumio[600]: Node JS runtime: 14 Jun 15 07:06:31 volumio volumio[600]: info: CoreCommandRouter::volumioGetState Jun 15 07:06:31 volumio volumio[600]: info: CorePlayQueue::getTrack 0 Jun 15 07:06:36 volumio kernel: usb 1-3: reset high-speed USB device number 3 using xhci_hcd Jun 15 07:06:36 volumio volumio[600]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 15 07:06:36 volumio volumio[600]: info: Preload queue cleared Jun 15 07:06:42 volumio volumio[600]: info: CoreCommandRouter::volumioGetState Jun 15 07:06:42 volumio volumio[600]: info: CorePlayQueue::getTrack 0 Jun 15 07:06:44 volumio volumio[600]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 15 07:06:44 volumio volumio[600]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Jun 15 07:06:44 volumio volumio[600]: info: CoreCommandRouter::volumioGetState Jun 15 07:06:44 volumio volumio[600]: info: CorePlayQueue::getTrack 0 Jun 15 07:06:49 volumio volumio[600]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 07:06:49 volumio volumio[600]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 07:06:49 volumio volumio[600]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 15 07:06:49 volumio volumio[600]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 15 07:06:49 volumio volumio[600]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 15 07:06:49 volumio volumio[600]: info: CoreCommandRouter::volumioGetBrowseSources Jun 15 07:06:49 volumio volumio[600]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 15 07:06:53 volumio volumio[600]: info: CoreCommandRouter::volumioGetState Jun 15 07:06:53 volumio volumio[600]: info: CorePlayQueue::getTrack 0 Jun 15 07:07:02 volumio systemd[1]: ifupdown-pre.service: Main process exited, code=exited, status=1/FAILURE Jun 15 07:07:02 volumio systemd[1]: ifupdown-pre.service: Failed with result 'exit-code'. Jun 15 07:07:02 volumio systemd[1]: Failed to start Helper to synchronize boot up for ifupdown. Jun 15 07:07:02 volumio systemd[1]: Dependency failed for Raise network interfaces. Jun 15 07:07:02 volumio systemd[1]: networking.service: Job networking.service/start failed with result 'dependency'. Jun 15 07:07:02 volumio systemd[1]: Reached target Network. Jun 15 07:07:02 volumio systemd[1]: Starting Music Player Daemon... Jun 15 07:07:02 volumio systemd[1]: Starting Network Time Service... Jun 15 07:07:02 volumio volumio[600]: info: Preload queue cleared Jun 15 07:07:02 volumio volumio[600]: info: Preloading song: mnt/INTERNAL/music/HI-FI顶级人声测试天碟《试音孙露 DXD》/02. 黄玫瑰.wav Jun 15 07:07:02 volumio volumio[600]: info: Preloading song: mnt/USB/android/MrBig - Wild World.mp3 Jun 15 07:07:02 volumio volumio[600]: info: Preloading song: mnt/USB/android/Bee Gees - How Deep Is Your Love_HQ.kgm.flac Jun 15 07:07:02 volumio volumio[600]: info: Preloading song: mnt/USB/B973-1E1E/复件 童丽 - 门前杨柳迎风摆_SQ.kgg.flac Jun 15 07:07:02 volumio volumio[600]: info: Preloading song: mnt/INTERNAL/music/HI-FI顶级人声测试天碟《试音孙露 DXD》/07. 悲伤的眼泪逆流成河.wav Jun 15 07:07:02 volumio systemd[1]: Starting OpenBSD Secure Shell server... Jun 15 07:07:02 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jun 15 07:07:02 volumio systemd[1]: Starting Permit User Sessions... Jun 15 07:07:02 volumio systemd[1]: Reached target Network is Online. Jun 15 07:07:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 15 07:07:02 volumio sudo[1045]: pam_unix(sudo:session): session closed for user root Jun 15 07:07:02 volumio volumio[600]: info: Exploding uri mnt/INTERNAL/music/HI-FI顶级人声测试天碟《试音孙露 DXD》/02. 黄玫瑰.wav in service mpd Jun 15 07:07:02 volumio systemd[1]: Starting Samba NMB Daemon... Jun 15 07:07:02 volumio sudo[1039]: pam_unix(sudo:session): session closed for user root Jun 15 07:07:02 volumio sudo[1047]: pam_unix(sudo:session): session closed for user root Jun 15 07:07:02 volumio sudo[1041]: pam_unix(sudo:session): session closed for user root Jun 15 07:07:02 volumio volumio[600]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 07:07:02 volumio volumio[600]: TypeError: Cannot read property 'then' of undefined Jun 15 07:07:02 volumio volumio[600]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2527:19) Jun 15 07:07:02 volumio volumio[600]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2325:21) Jun 15 07:07:02 volumio volumio[600]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1359:16) Jun 15 07:07:02 volumio volumio[600]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Jun 15 07:07:02 volumio volumio[600]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Jun 15 07:07:02 volumio volumio[600]: at Timeout._onTimeout (/volumio/app/playqueue.js:146:100) Jun 15 07:07:02 volumio volumio[600]: at listOnTimeout (internal/timers.js:557:17) Jun 15 07:07:02 volumio volumio[600]: at processTimers (internal/timers.js:500:7) Jun 15 07:07:02 volumio volumio[600]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 07:07:02 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Jun 15 07:07:02 volumio systemd[1]: Starting /etc/rc.local Compatibility... Jun 15 07:07:02 volumio systemd[1]: Started Permit User Sessions. Jun 15 07:07:02 volumio ntpd[1454]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 15 07:07:02 volumio ntpd[1454]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jun 15 07:07:02 volumio systemd[1]: Started Network Time Service. Jun 15 07:07:02 volumio ntpd[1469]: proto: precision = 0.162 usec (-22) Jun 15 07:07:02 volumio systemd[1]: Started /etc/rc.local Compatibility. Jun 15 07:07:02 volumio ntpd[1469]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 15 07:07:02 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Jun 15 07:07:02 volumio ntpd[1469]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 15 07:07:02 volumio ntpd[1469]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 900 days ago Jun 15 07:07:02 volumio ntpd[1469]: Listen and drop on 0 v6wildcard [::]:123 Jun 15 07:07:02 volumio ntpd[1469]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 15 07:07:02 volumio ntpd[1469]: Listen normally on 2 lo 127.0.0.1:123 Jun 15 07:07:02 volumio ntpd[1469]: Listen normally on 3 eth0 192.168.10.45:123 Jun 15 07:07:02 volumio ntpd[1469]: Listening on routing socket on fd #20 for interface updates Jun 15 07:07:02 volumio ntpd[1469]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 15 07:07:02 volumio ntpd[1469]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 15 07:07:02 volumio systemd[1]: Starting Hold until boot process finishes up... Jun 15 07:07:02 volumio systemd[1]: Received SIGRTMIN+21 from PID 279 (plymouthd). Jun 15 07:07:02 volumio sshd[1472]: Server listening on 0.0.0.0 port 22. Jun 15 07:07:02 volumio systemd[1]: Received SIGRTMIN+21 from PID 279 (plymouthd). Jun 15 07:07:02 volumio sshd[1472]: Server listening on :: port 22. Jun 15 07:07:02 volumio systemd[1]: Started OpenBSD Secure Shell server. Jun 15 07:07:02 volumio systemd[1]: plymouth-quit.service: Succeeded. Jun 15 07:07:02 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Jun 15 07:07:02 volumio systemd[1]: plymouth-start.service: Succeeded. Jun 15 07:07:02 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Jun 15 07:07:02 volumio systemd[1]: Started Hold until boot process finishes up. Jun 15 07:07:02 volumio systemd[1]: Started Getty on tty1. Jun 15 07:07:02 volumio ifplugd(eth0)[1494]: ifplugd 0.28 initializing. Jun 15 07:07:02 volumio ifplugd(eth0)[1494]: Using interface eth0/6C:0B:84:A1:D7:D2 with driver (version: 6.6.25-volumio) Jun 15 07:07:02 volumio ifplugd(eth0)[1494]: Using detection mode: SIOCETHTOOL Jun 15 07:07:02 volumio ifplugd(eth0)[1494]: Initialization complete, link beat detected. Jun 15 07:07:02 volumio systemd[1]: Reached target Login Prompts. Jun 15 07:07:02 volumio ifplugd(eth0)[1494]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jun 15 07:07:02 volumio ifplugd(eth0)[1494]: client: ifup: interface eth0 already configured Jun 15 07:07:03 volumio ifplugd(eth0)[1494]: Program executed successfully. Jun 15 07:07:03 volumio ifplugd[1463]: Network Interface Plugging Daemon...start eth0...done. Jun 15 07:07:03 volumio systemd[1]: Started LSB: Brings up/down network automatically. Jun 15 07:07:03 volumio nmbd[1467]: [2025/06/15 07:07:03.144170, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jun 15 07:07:03 volumio nmbd[1467]: started asyncdns process 1500 Jun 15 07:07:03 volumio systemd[1]: Started Samba NMB Daemon. Jun 15 07:07:03 volumio nmbd[1467]: [2025/06/15 07:07:03.150984, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 15 07:07:03 volumio nmbd[1467]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 15 07:07:03 volumio systemd[1]: Starting Samba Winbind Daemon... Jun 15 07:07:03 volumio sudo[1504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-15 07:06 Jun 15 07:07:03 volumio sudo[1504]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"