-- Logs begin at Thu 2025-09-11 01:14:20 UTC, end at Thu 2025-09-11 01:16:38 UTC. -- Sep 11 01:15:00 opera volumio[1423]: info: Loading plugin "tidal"... Sep 11 01:15:00 opera dhcpcd[1219]: wlan0: using IPv4LL address 169.254.189.238 Sep 11 01:15:00 opera dhcpcd[1219]: eth0: adding route to 192.168.1.0/24 Sep 11 01:15:00 opera avahi-daemon[741]: Registering new address record for 169.254.189.238 on wlan0.IPv4. Sep 11 01:15:00 opera dhcpcd[1219]: eth0: adding default route via 192.168.1.1 Sep 11 01:15:00 opera dhcpcd[1219]: wlan0: adding host route to 169.254.189.238 via 127.0.0.1 Sep 11 01:15:00 opera dhcpcd[1219]: wlan0: adding route to 169.254.0.0/16 Sep 11 01:15:00 opera dhcpcd[1219]: eth0: removing route to 192.168.1.0/24 Sep 11 01:15:00 opera dhcpcd[1219]: eth0: removing default route via 192.168.1.1 Sep 11 01:15:01 opera volumio[1423]: info: Loading plugin "volspotconnect2"... Sep 11 01:15:01 opera ntpd[836]: Listen normally on 5 wlan0 169.254.189.238 UDP 123 Sep 11 01:15:01 opera ntpd[836]: peers refreshed Sep 11 01:15:01 opera volumio[1423]: info: Loading plugin "updater_comm"... Sep 11 01:15:01 opera volumio[1423]: info: Plugin mpdemulation is not enabled Sep 11 01:15:01 opera volumio[1423]: info: Loading plugin "rest_api"... Sep 11 01:15:01 opera volumio[1423]: info: Loading plugin "websocket"... Sep 11 01:15:01 opera volumio[1423]: info: ___________ START PLUGINS ___________ Sep 11 01:15:01 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 11 01:15:01 opera volumio[1423]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 11 01:15:01 opera volumio[1423]: info: [1757553301946] CoreMusicLibrary::Adding element Media Servers Sep 11 01:15:01 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 11 01:15:01 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 11 01:15:01 opera volumio[1423]: info: Adding METAVOLUMIO REST API Endpoints Sep 11 01:15:01 opera volumio[1423]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Sep 11 01:15:01 opera sudo[1712]: pam_unix(sudo:auth): conversation failed Sep 11 01:15:01 opera sudo[1712]: pam_unix(sudo:auth): auth could not identify password for [volumio] Sep 11 01:15:02 opera volumio[1423]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 11 01:15:02 opera volumio[1423]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 11 01:15:02 opera volumio[1423]: info: Preparing CD Folders Sep 11 01:15:02 opera volumio[1423]: info: Adding CD REST API Endpoints Sep 11 01:15:02 opera volumio[1423]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller Sep 11 01:15:02 opera volumio[1423]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller Sep 11 01:15:02 opera volumio[1423]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller Sep 11 01:15:02 opera volumio[1423]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Sep 11 01:15:02 opera volumio[1423]: info: Detecting CD presence with Flag file Sep 11 01:15:02 opera volumio[1423]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 11 01:15:02 opera volumio[1423]: info: [1757553302047] CoreMusicLibrary::Adding element Last_100 Sep 11 01:15:02 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 11 01:15:02 opera volumio[1423]: info: Starting RAAT Plugin Sep 11 01:15:02 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Sep 11 01:15:02 opera volumio[1423]: info: Additional UI Settings Added for plugin music_service/raat Sep 11 01:15:02 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 01:15:02 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 01:15:02 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 01:15:02 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 11 01:15:02 opera volumio[1423]: info: Not Reporting Auto name since its the default one Sep 11 01:15:02 opera volumio[1423]: info: Streaming services startup Sep 11 01:15:02 opera volumio[1423]: info: Starting Streaming Daemon Sep 11 01:15:02 opera sudo[1729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 11 01:15:02 opera sudo[1729]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 01:15:02 opera volumio[1423]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 11 01:15:02 opera volumio[1423]: info: [1757553302119] CoreMusicLibrary::Adding element Webradio Sep 11 01:15:02 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 11 01:15:02 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 11 01:15:02 opera systemd[1]: Stopping Volumio Streaming Daemon... Sep 11 01:15:02 opera volumio[1423]: error: Hi Res Audio Failed Login: Missing Login Data Sep 11 01:15:02 opera systemd[1]: Starting Volumio Streaming Daemon... Sep 11 01:15:02 opera systemd[1]: Started Volumio Streaming Daemon. Sep 11 01:15:02 opera sudo[1729]: pam_unix(sudo:session): session closed for user root Sep 11 01:15:02 opera volumio-streaming-daemon[1739]: ############################ Sep 11 01:15:02 opera volumio-streaming-daemon[1739]: # Volumio Streaming Daemon # Sep 11 01:15:02 opera volumio-streaming-daemon[1739]: # Running on port 7777 # Sep 11 01:15:02 opera volumio-streaming-daemon[1739]: ############################ Sep 11 01:15:02 opera volumio-streaming-daemon[1739]: Checking variant in static cache Sep 11 01:15:02 opera volumio-streaming-daemon[1739]: Enabled services: Sep 11 01:15:02 opera volumio-streaming-daemon[1739]: 1) tidal Sep 11 01:15:02 opera volumio-streaming-daemon[1739]: 2) qobuz Sep 11 01:15:02 opera sudo[1750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Sep 11 01:15:02 opera volumio[1423]: info: Loading i18n strings for locale zh Sep 11 01:15:02 opera volumio[1423]: Updating browse sources language Sep 11 01:15:02 opera volumio[1423]: Cannot find translation for sourceMedia Servers Sep 11 01:15:02 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 11 01:15:02 opera sudo[1750]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 01:15:02 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 11 01:15:02 opera systemd[1]: Starting Volspotconnect2 Daemon... Sep 11 01:15:02 opera systemd[1]: Started Volspotconnect2 Daemon. Sep 11 01:15:02 opera volumio[1423]: info: CoreCommandRouter::initPlayerControls Sep 11 01:15:02 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 01:15:02 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Sep 11 01:15:02 opera volumio[1755]: /bin/bash: /tmp/startconnect.sh: No such file or directory Sep 11 01:15:02 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 01:15:02 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 01:15:02 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 01:15:02 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 01:15:02 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 01:15:02 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 11 01:15:02 opera volumio[1423]: info: BOOT COMPLETED Sep 11 01:15:02 opera volumio[1423]: [Metrics] CommandRouter: 10s 113.93ms Sep 11 01:15:02 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 11 01:15:02 opera systemd[1]: volspotconnect2.service: main process exited, code=exited, status=127/n/a Sep 11 01:15:02 opera systemd[1]: Unit volspotconnect2.service entered failed state. Sep 11 01:15:02 opera sudo[1750]: pam_unix(sudo:session): session closed for user root Sep 11 01:15:04 opera systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. Sep 11 01:15:04 opera systemd[1]: Stopping Volspotconnect2 Daemon... Sep 11 01:15:04 opera systemd[1]: Starting Volspotconnect2 Daemon... Sep 11 01:15:04 opera systemd[1]: Started Volspotconnect2 Daemon. Sep 11 01:15:04 opera volumio[1765]: /bin/bash: /tmp/startconnect.sh: No such file or directory Sep 11 01:15:04 opera systemd[1]: volspotconnect2.service: main process exited, code=exited, status=127/n/a Sep 11 01:15:04 opera systemd[1]: Unit volspotconnect2.service entered failed state. Sep 11 01:15:04 opera dhcpcd[1219]: wlan0: soliciting a DHCP lease Sep 11 01:15:04 opera dhcpd[1453]: DHCPDISCOVER from 48:e7:da:4e:ef:61 via wlan0 Sep 11 01:15:05 opera dhcpd[1453]: DHCPOFFER on 192.168.211.23 to 48:e7:da:4e:ef:61 (opera) via wlan0 Sep 11 01:15:06 opera systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. Sep 11 01:15:06 opera systemd[1]: Stopping Volspotconnect2 Daemon... Sep 11 01:15:06 opera systemd[1]: Starting Volspotconnect2 Daemon... Sep 11 01:15:06 opera systemd[1]: Started Volspotconnect2 Daemon. Sep 11 01:15:06 opera volumio[1768]: /bin/bash: /tmp/startconnect.sh: No such file or directory Sep 11 01:15:06 opera systemd[1]: volspotconnect2.service: main process exited, code=exited, status=127/n/a Sep 11 01:15:06 opera systemd[1]: Unit volspotconnect2.service entered failed state. Sep 11 01:15:07 opera volumio[1423]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Sep 11 01:15:07 opera volumio[1423]: info: CoreCommandRouter::Close All Modals sent Sep 11 01:15:07 opera volumio[1423]: info: CoreCommandRouter::Close All Modals sent Sep 11 01:15:07 opera volumio[1423]: Express server listening on port 3000 Sep 11 01:15:07 opera volumio[1423]: [Metrics] WebUI: 15s 965.03ms Sep 11 01:15:07 opera volumio[1423]: Volumio Calling Home Sep 11 01:15:07 opera volumio[1423]: info: CoreStateMachine::resetVolumioState Sep 11 01:15:07 opera volumio[1423]: info: CoreStateMachine::getcurrentVolume Sep 11 01:15:07 opera volumio[1423]: info: CoreCommandRouter::volumioRetrievevolume Sep 11 01:15:07 opera volumio[1423]: info: Stopping AccessToken refresher cron for QOBUZ Sep 11 01:15:07 opera volumio[1423]: info: AccessToken refresher cron started for QOBUZ Sep 11 01:15:07 opera volumio[1423]: info: Stopping AccessToken refresher cron Sep 11 01:15:07 opera volumio[1423]: info: AccessToken refresher cron started Sep 11 01:15:07 opera volumio[1423]: info: CoreStateMachine::pushState Sep 11 01:15:07 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:15:07 opera volumio[1423]: info: CoreCommandRouter::volumioPushState Sep 11 01:15:07 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 01:15:07 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 11 01:15:07 opera sudo[1780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 11 01:15:07 opera sudo[1780]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 01:15:07 opera sudo[1780]: pam_unix(sudo:session): session closed for user root Sep 11 01:15:07 opera sudo[1787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 11 01:15:07 opera sudo[1787]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 01:15:07 opera sudo[1787]: pam_unix(sudo:session): session closed for user root Sep 11 01:15:07 opera sudo[1791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Sep 11 01:15:07 opera sudo[1791]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 01:15:07 opera volumio[1423]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 11 01:15:07 opera systemd[1]: Configuration file /lib/systemd/system/volumiobt.service is marked executable. Please remove executable permission bits. Proceeding anyway. Sep 11 01:15:07 opera systemd[1]: Starting Volumio Bluetooth Module... Sep 11 01:15:07 opera systemd[1]: Started Volumio Bluetooth Module. Sep 11 01:15:07 opera sudo[1791]: pam_unix(sudo:session): session closed for user root Sep 11 01:15:07 opera sudo[1808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Sep 11 01:15:07 opera sudo[1808]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 01:15:07 opera volumio[1423]: info: Setting Device type: Tinkerboard Sep 11 01:15:07 opera volumio[1423]: [SpotifyConnect] Vollibrespot Daemon Started Sep 11 01:15:07 opera volumio[1423]: [SpotifyConnect] Starting metadata listener Sep 11 01:15:07 opera volumio[1423]: [SpotifyConnect] FE => [ 1, [length]: 1 ] Sep 11 01:15:07 opera volumiobt[1803]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Sep 11 01:15:07 opera sudo[1808]: pam_unix(sudo:session): session closed for user root Sep 11 01:15:07 opera volumio[1423]: info: RAAT Albumart path created successfully Sep 11 01:15:07 opera volumio[1423]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'Opera' Sep 11 01:15:07 opera volumio[1423]: We trust you have received the usual lecture from the local System Sep 11 01:15:07 opera volumio[1423]: Administrator. It usually boils down to these three things: Sep 11 01:15:07 opera volumio[1423]: #1) Respect the privacy of others. Sep 11 01:15:07 opera volumio[1423]: #2) Think before you type. Sep 11 01:15:07 opera volumio[1423]: #3) With great power comes great responsibility. Sep 11 01:15:07 opera volumio[1423]: sudo: no tty present and no askpass program specified Sep 11 01:15:07 opera volumio[1423]: info: MPD running with PID742 Sep 11 01:15:07 opera volumio[1423]: ,establishing connection Sep 11 01:15:07 opera sudo[1818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Sep 11 01:15:07 opera sudo[1818]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 01:15:07 opera sudo[1818]: pam_unix(sudo:session): session closed for user root Sep 11 01:15:07 opera volumio[1423]: info: Volumio BT Module successfully started Sep 11 01:15:08 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 11 01:15:08 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Sep 11 01:15:08 opera volumio[1423]: No protocol specified Sep 11 01:15:08 opera volumio[1423]: xcb_connection_has_error() returned true Sep 11 01:15:08 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 01:15:08 opera volumio[1423]: info: CoreCommandRouter::volumioGetState Sep 11 01:15:08 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:15:08 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 01:15:08 opera volumio[1423]: info: Reloading queue from file Sep 11 01:15:08 opera sudo[1830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Sep 11 01:15:08 opera sudo[1830]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 01:15:08 opera volumio[1423]: error: updateQueue error: null Sep 11 01:15:08 opera systemd[1]: Starting RAAT DAEMON... Sep 11 01:15:08 opera systemd[1]: Started RAAT DAEMON. Sep 11 01:15:08 opera sudo[1830]: pam_unix(sudo:session): session closed for user root Sep 11 01:15:08 opera volumio[1423]: info: CoreStateMachine::setRepeat false single undefined Sep 11 01:15:08 opera volumio[1423]: info: CoreStateMachine::pushState Sep 11 01:15:08 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:15:08 opera volumio[1423]: info: CoreCommandRouter::volumioPushState Sep 11 01:15:08 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 01:15:08 opera volumio[1423]: info: CoreStateMachine::setRandom false Sep 11 01:15:08 opera volumio[1423]: info: CoreStateMachine::pushState Sep 11 01:15:08 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:15:08 opera volumio[1423]: info: CoreCommandRouter::volumioPushState Sep 11 01:15:08 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 01:15:08 opera volumio[1423]: info: Raat Daemon started successfully Sep 11 01:15:08 opera volumio[1423]: info: Starting Shairport Sync Sep 11 01:15:08 opera volumio[1423]: info: Starting Shairport Sync Sep 11 01:15:08 opera sudo[1853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 11 01:15:08 opera sudo[1853]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 01:15:08 opera sudo[1856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 11 01:15:08 opera systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Sep 11 01:15:08 opera systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 11 01:15:08 opera sudo[1853]: pam_unix(sudo:session): session closed for user root Sep 11 01:15:08 opera sudo[1856]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 01:15:08 opera sudo[1862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 11 01:15:08 opera systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 11 01:15:08 opera systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Sep 11 01:15:08 opera systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 11 01:15:08 opera sudo[1856]: pam_unix(sudo:session): session closed for user root Sep 11 01:15:08 opera sudo[1862]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 01:15:08 opera systemd[1]: Starting UPnP Renderer front-end to MPD... Sep 11 01:15:08 opera systemd[1]: Started UPnP Renderer front-end to MPD. Sep 11 01:15:08 opera sudo[1862]: pam_unix(sudo:session): session closed for user root Sep 11 01:15:08 opera volumio[1423]: info: Executing endpoint restartRAATSocket Sep 11 01:15:08 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Sep 11 01:15:08 opera volumio[1423]: Discovery: adding 0d2ab1ac-cd1b-4383-8f99-228d8a9185ac Sep 11 01:15:08 opera volumio[1423]: info: mDNS: Found device Opera Sep 11 01:15:08 opera volumio[1423]: info: CoreCommandRouter::volumioGetState Sep 11 01:15:08 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:15:08 opera volumio[1423]: error: Could not retrieve plugin audio_interface multiroom Sep 11 01:15:08 opera volumio[1423]: error: Could not retrieve plugin audio_interface multiroom Sep 11 01:15:08 opera volumio[1423]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 11 01:15:08 opera volumio[1423]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 11 01:15:08 opera volumio[1423]: info: Upmpdcli Daemon Started Sep 11 01:15:08 opera volumio[1423]: info: Shairport-Sync Started Sep 11 01:15:08 opera volumio[1423]: Error adding Membership: Error: addMembership EINVAL Sep 11 01:15:08 opera volumio[1423]: info: Shairport-Sync Started Sep 11 01:15:08 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 11 01:15:08 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 11 01:15:08 opera volumio[1423]: No protocol specified Sep 11 01:15:08 opera volumio[1423]: xcb_connection_has_error() returned true Sep 11 01:15:08 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Sep 11 01:15:08 opera volumio[1423]: info: Stopping RAAT Plugin Sep 11 01:15:08 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 11 01:15:08 opera volumio[1423]: Discovery: this is already registered, 0d2ab1ac-cd1b-4383-8f99-228d8a9185ac Sep 11 01:15:08 opera volumio[1423]: info: mDNS: Found device Opera Sep 11 01:15:08 opera volumio[1423]: info: CoreCommandRouter::volumioGetState Sep 11 01:15:08 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:15:08 opera volumio[1423]: error: Could not retrieve plugin audio_interface multiroom Sep 11 01:15:08 opera volumio[1423]: error: Could not retrieve plugin audio_interface multiroom Sep 11 01:15:08 opera sudo[1900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop raat-daemon.service Sep 11 01:15:08 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:15:08 opera sudo[1900]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 01:15:08 opera systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. Sep 11 01:15:08 opera systemd[1]: Stopping Volspotconnect2 Daemon... Sep 11 01:15:08 opera systemd[1]: Starting Volspotconnect2 Daemon... Sep 11 01:15:08 opera systemd[1]: Started Volspotconnect2 Daemon. Sep 11 01:15:08 opera systemd[1]: Stopping RAAT DAEMON... Sep 11 01:15:08 opera systemd[1]: Stopped RAAT DAEMON. Sep 11 01:15:08 opera sudo[1900]: pam_unix(sudo:session): session closed for user root Sep 11 01:15:08 opera volumio[1905]: vollibrespot v0.1.9 c98fd5d 2019-11-08 (librespot 4674946 2019-11-08) -- Built On 2019-11-08 Sep 11 01:15:08 opera volumio[1423]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 11 01:15:08 opera sudo[1915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Sep 11 01:15:08 opera sudo[1915]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 01:15:08 opera volumiobt[1803]: pulseaudio: no process found Sep 11 01:15:08 opera sudo[1915]: pam_unix(sudo:session): session closed for user root Sep 11 01:15:08 opera volumio[1905]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Sep 11 01:15:09 opera volumio[1423]: info: CoreCommandRouter::volumioGetState Sep 11 01:15:09 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:15:09 opera volumio[1423]: info: Raat Daemon stopped successfully Sep 11 01:15:09 opera dbus[743]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' Sep 11 01:15:09 opera systemd[1]: Starting RealtimeKit Scheduling Policy Service... Sep 11 01:15:09 opera kernel: IPVS: Creating netns size=1496 id=2 Sep 11 01:15:09 opera kernel: IPVS: ftp: loaded support on port[0] = 21 Sep 11 01:15:09 opera dbus[743]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Sep 11 01:15:09 opera systemd[1]: Started RealtimeKit Scheduling Policy Service. Sep 11 01:15:09 opera rtkit-daemon[1926]: Successfully called chroot. Sep 11 01:15:09 opera rtkit-daemon[1926]: Successfully dropped privileges. Sep 11 01:15:09 opera rtkit-daemon[1926]: Successfully limited resources. Sep 11 01:15:09 opera rtkit-daemon[1926]: Running. Sep 11 01:15:09 opera rtkit-daemon[1926]: Watchdog thread running. Sep 11 01:15:09 opera rtkit-daemon[1926]: Canary thread running. Sep 11 01:15:09 opera dhcpd[1453]: DHCPDISCOVER from 48:e7:da:4e:ef:61 (opera) via wlan0 Sep 11 01:15:09 opera dhcpd[1453]: DHCPOFFER on 192.168.211.23 to 48:e7:da:4e:ef:61 (opera) via wlan0 Sep 11 01:15:09 opera pulseaudio[1925]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Sep 11 01:15:09 opera pulseaudio[1925]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Sep 11 01:15:09 opera volumiobt[1803]: Applying permissions Sep 11 01:15:09 opera volumiobt[1803]: Setting BT discoverable and pairable Sep 11 01:15:09 opera pulseaudio[1925]: org.bluez.Manager.GetProperties() failed: org.freedesktop.DBus.Error.UnknownMethod: Method "GetProperties" with signature "" on interface "org.bluez.Manager" doesn't exist Sep 11 01:15:09 opera bluetoothd[916]: Endpoint registered: sender=:1.20 path=/MediaEndpoint/A2DPSource Sep 11 01:15:09 opera bluetoothd[916]: Endpoint registered: sender=:1.20 path=/MediaEndpoint/A2DPSink Sep 11 01:15:09 opera volumiobt[1803]: [103B blob data] Sep 11 01:15:09 opera volumiobt[1803]: [71B blob data] Sep 11 01:15:09 opera volumiobt[1803]: [75B blob data] Sep 11 01:15:09 opera volumiobt[1803]: [87B blob data] Sep 11 01:15:09 opera volumiobt[1803]: [bluetooth]# -e power on Sep 11 01:15:09 opera volumiobt[1803]: Invalid command Sep 11 01:15:09 opera volumiobt[1803]: [bluetooth]# agent on Sep 11 01:15:09 opera volumiobt[1803]: [bluetooth]# discoverable on Sep 11 01:15:09 opera volumiobt[1803]: [bluetooth]# pairable on Sep 11 01:15:09 opera volumiobt[1803]: [bluetooth]# agent NoInputNoOutput Sep 11 01:15:09 opera volumiobt[1803]: Failed to register agent object Sep 11 01:15:09 opera volumiobt[1803]: [bluetooth]# default-agent Sep 11 01:15:09 opera volumiobt[1803]: No agent is registered Sep 11 01:15:09 opera volumiobt[1803]: [bluetooth]# quit Sep 11 01:15:09 opera volumiobt[1803]: [61B blob data] Sep 11 01:15:09 opera volumiobt[1803]: [89B blob data] Sep 11 01:15:09 opera volumiobt[1803]: [62B blob data] Sep 11 01:15:09 opera volumio[1423]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 11 01:15:09 opera volumio[1423]: info: Volumio called home Sep 11 01:15:10 opera volumio-streaming-daemon[1739]: Checking variant in static cache Sep 11 01:15:11 opera volumio[1423]: info: Fetching Streaming Services browse cache Sep 11 01:15:12 opera volumio-remote-updater[719]: [2025-09-11 01:15:12] [connect] Successful connection Sep 11 01:15:13 opera volumio-remote-updater[719]: [2025-09-11 01:15:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1757553312 101 Sep 11 01:15:16 opera dhcpd[1453]: DHCPDISCOVER from 48:e7:da:4e:ef:61 (opera) via wlan0 Sep 11 01:15:16 opera dhcpd[1453]: DHCPOFFER on 192.168.211.23 to 48:e7:da:4e:ef:61 (opera) via wlan0 Sep 11 01:15:16 opera volumio[1423]: info: CoreCommandRouter::volumioGetState Sep 11 01:15:16 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:15:16 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 11 01:15:16 opera volumio[1423]: error: Could not retrieve plugin audio_interface multiroom Sep 11 01:15:16 opera volumio[1423]: info: Listing playlists Sep 11 01:15:16 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 11 01:15:16 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 11 01:15:16 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 11 01:15:16 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 11 01:15:29 opera volumio[1423]: verbose: ControllerMpd::sendMpdCommand status Sep 11 01:15:29 opera volumio[1423]: info: Sep 11 01:15:29 opera volumio[1423]: ---------------------------- MPD announces state update: update Sep 11 01:15:29 opera volumio[1423]: info: ControllerMpd::getState Sep 11 01:15:29 opera volumio[1423]: verbose: ControllerMpd::sendMpdCommand status Sep 11 01:15:29 opera volumio[1423]: info: sendMpdCommand status took 5 milliseconds Sep 11 01:15:29 opera volumio[1423]: info: sendMpdCommand status took 2 milliseconds Sep 11 01:15:29 opera volumio[1423]: verbose: ControllerMpd::parseState Sep 11 01:15:30 opera volumio[1423]: info: Command Router : Notfying DB Updatetrue Sep 11 01:15:30 opera volumio[1423]: verbose: ControllerMpd::parseState Sep 11 01:15:30 opera volumio[1423]: info: ControllerMpd::pushState Sep 11 01:15:30 opera volumio[1423]: info: CoreCommandRouter::servicePushState Sep 11 01:15:30 opera volumio[1423]: info: CoreStateMachine::pushState Sep 11 01:15:30 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:15:30 opera volumio[1423]: info: CoreCommandRouter::volumioPushState Sep 11 01:15:30 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 01:15:30 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:15:30 opera volumio[1423]: 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} Sep 11 01:15:30 opera volumio[1423]: verbose: CURRENT POSITION 0 Sep 11 01:15:30 opera volumio[1423]: info: CoreStateMachine::syncState stateService stop Sep 11 01:15:30 opera volumio[1423]: info: CoreStateMachine::syncState currentStatus stop Sep 11 01:15:30 opera volumio[1423]: info: CoreStateMachine::pushState Sep 11 01:15:30 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:15:30 opera volumio[1423]: info: CoreCommandRouter::volumioPushState Sep 11 01:15:30 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 01:15:30 opera volumio[1423]: info: No code Sep 11 01:15:30 opera volumio[1423]: info: CoreStateMachine::pushState Sep 11 01:15:30 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:15:30 opera volumio[1423]: info: CoreCommandRouter::volumioPushState Sep 11 01:15:30 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 01:15:30 opera volumio[1423]: info: ------------------------------ 164ms Sep 11 01:15:30 opera volumio[1423]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 11 01:15:30 opera volumio[1423]: info: Updating RAAT Signal Path Sep 11 01:15:30 opera volumio[1423]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 11 01:15:30 opera volumio[1423]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 11 01:15:30 opera volumio[1423]: info: Updating RAAT Signal Path Sep 11 01:15:30 opera volumio[1423]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 11 01:15:30 opera volumio[1423]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 11 01:15:30 opera volumio[1423]: info: Updating RAAT Signal Path Sep 11 01:15:30 opera volumio[1423]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 11 01:15:32 opera dhcpd[1453]: DHCPDISCOVER from 48:e7:da:4e:ef:61 (opera) via wlan0 Sep 11 01:15:32 opera dhcpd[1453]: DHCPOFFER on 192.168.211.23 to 48:e7:da:4e:ef:61 (opera) via wlan0 Sep 11 01:15:32 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 11 01:15:32 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 11 01:15:37 opera volumio[1905]: [Vollibrespot] : couldn't parse packet from V4(192.168.1.10:5353): query type 65 is invalid Sep 11 01:15:37 opera volumio[1905]: [Vollibrespot] : couldn't parse packet from V4(192.168.1.10:5353): query type 65 is invalid Sep 11 01:15:37 opera volumio[1905]: [Vollibrespot] : couldn't parse packet from V4(192.168.1.10:5353): query type 65 is invalid Sep 11 01:15:37 opera sudo[2042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 11 01:15:37 opera sudo[2044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 11 01:15:37 opera sudo[2042]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 01:15:37 opera sudo[2044]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 01:15:37 opera sudo[2044]: pam_unix(sudo:session): session closed for user root Sep 11 01:15:37 opera sudo[2042]: pam_unix(sudo:session): session closed for user root Sep 11 01:15:38 opera volumio[1423]: info: CoreCommandRouter::volumioGetState Sep 11 01:15:38 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:15:38 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 11 01:15:38 opera volumio[1423]: error: Could not retrieve plugin audio_interface multiroom Sep 11 01:15:38 opera volumio[1423]: info: Listing playlists Sep 11 01:15:38 opera volumio[1905]: [Vollibrespot] : couldn't parse packet from V4(192.168.1.10:5353): query type 65 is invalid Sep 11 01:15:42 opera volumio[1423]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 11 01:15:42 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 11 01:15:43 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 11 01:15:43 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 11 01:15:43 opera volumio[1423]: info: Received Get System Version Sep 11 01:15:43 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 11 01:15:45 opera volumio[1423]: info: CALLMETHOD: miscellanea appearance setLanguage [object Object] Sep 11 01:15:45 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Sep 11 01:15:45 opera volumio[1423]: info: Loading i18n strings for locale zh Sep 11 01:15:45 opera volumio[1423]: Updating browse sources language Sep 11 01:15:45 opera volumio[1423]: Cannot find translation for sourceMedia Servers Sep 11 01:15:45 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 11 01:15:46 opera volumio[1423]: info: Fetching Streaming Services browse cache Sep 11 01:15:48 opera volumio[1423]: info: CoreCommandRouter::volumioGetVisibleSources Sep 11 01:15:48 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 11 01:15:48 opera volumio[1423]: info: CoreCommandRouter::volumioGetQueue Sep 11 01:15:48 opera volumio[1423]: info: CoreStateMachine::getQueue Sep 11 01:15:48 opera volumio[1423]: info: CorePlayQueue::getQueue Sep 11 01:15:48 opera volumio[1905]: [Vollibrespot] : couldn't parse packet from V4(192.168.1.10:5353): query type 65 is invalid Sep 11 01:15:49 opera volumio[1905]: [Vollibrespot] : couldn't parse packet from V4(192.168.1.10:5353): query type 65 is invalid Sep 11 01:15:50 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 11 01:15:50 opera volumio[1423]: info: CURURI: music-library Sep 11 01:15:52 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 11 01:15:52 opera volumio[1423]: info: CURURI: music-library/USB Sep 11 01:15:52 opera volumio[1423]: verbose: ControllerMpd::sendMpdCommand status Sep 11 01:15:52 opera volumio[1423]: info: Sep 11 01:15:52 opera volumio[1423]: ---------------------------- MPD announces state update: update Sep 11 01:15:52 opera volumio[1423]: info: ControllerMpd::getState Sep 11 01:15:52 opera volumio[1423]: verbose: ControllerMpd::sendMpdCommand status Sep 11 01:15:52 opera volumio[1423]: info: sendMpdCommand status took 2 milliseconds Sep 11 01:15:52 opera volumio[1423]: info: sendMpdCommand status took 1 milliseconds Sep 11 01:15:52 opera volumio[1423]: verbose: ControllerMpd::parseState Sep 11 01:15:52 opera volumio[1423]: info: Command Router : Notfying DB Updatetrue Sep 11 01:15:52 opera volumio[1423]: info: CoreCommandRouter::Close All Modals sent Sep 11 01:15:52 opera volumio[1423]: verbose: ControllerMpd::parseState Sep 11 01:15:52 opera volumio[1423]: info: ControllerMpd::pushState Sep 11 01:15:52 opera volumio[1423]: info: CoreCommandRouter::servicePushState Sep 11 01:15:52 opera volumio[1423]: info: CoreStateMachine::pushState Sep 11 01:15:52 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:15:52 opera volumio[1423]: info: CoreCommandRouter::volumioPushState Sep 11 01:15:52 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 01:15:52 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:15:52 opera volumio[1423]: 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} Sep 11 01:15:52 opera volumio[1423]: verbose: CURRENT POSITION 0 Sep 11 01:15:52 opera volumio[1423]: info: CoreStateMachine::syncState stateService stop Sep 11 01:15:52 opera volumio[1423]: info: CoreStateMachine::syncState currentStatus stop Sep 11 01:15:52 opera volumio[1423]: info: CoreStateMachine::pushState Sep 11 01:15:52 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:15:52 opera volumio[1423]: info: CoreCommandRouter::volumioPushState Sep 11 01:15:52 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 01:15:52 opera volumio[1423]: info: No code Sep 11 01:15:52 opera volumio[1423]: info: CoreStateMachine::pushState Sep 11 01:15:52 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:15:52 opera volumio[1423]: info: CoreCommandRouter::volumioPushState Sep 11 01:15:52 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 01:15:52 opera volumio[1423]: info: ------------------------------ 66ms Sep 11 01:15:52 opera volumio[1423]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 11 01:15:52 opera volumio[1423]: info: Updating RAAT Signal Path Sep 11 01:15:52 opera volumio[1423]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 11 01:15:52 opera volumio[1423]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 11 01:15:52 opera volumio[1423]: info: Updating RAAT Signal Path Sep 11 01:15:52 opera volumio[1423]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 11 01:15:52 opera volumio[1423]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 11 01:15:52 opera volumio[1423]: info: Updating RAAT Signal Path Sep 11 01:15:52 opera volumio[1423]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 11 01:15:56 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 11 01:15:56 opera volumio[1423]: info: CURURI: music-library/USB/4DSD600 Sep 11 01:15:58 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 11 01:15:58 opera volumio[1423]: info: CURURI: music-library/USB/4DSD600/FIM一听钟情25张经典唱片 Sep 11 01:16:04 opera dhcpd[1453]: DHCPDISCOVER from 48:e7:da:4e:ef:61 (opera) via wlan0 Sep 11 01:16:04 opera dhcpd[1453]: DHCPOFFER on 192.168.211.23 to 48:e7:da:4e:ef:61 (opera) via wlan0 Sep 11 01:16:06 opera volumio[1423]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 11 01:16:06 opera volumio[1423]: info: CoreStateMachine::ClearQueue Sep 11 01:16:06 opera volumio[1423]: info: CoreStateMachine::stop Sep 11 01:16:06 opera volumio[1423]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 01:16:06 opera volumio[1423]: verbose: UNSET VOLATILE Sep 11 01:16:06 opera volumio[1423]: info: CorePlayQueue::clearPlayQueue Sep 11 01:16:06 opera volumio[1423]: info: CorePlayQueue::saveQueue Sep 11 01:16:06 opera volumio[1423]: info: CoreCommandRouter::volumioPushState Sep 11 01:16:06 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 01:16:06 opera volumio[1423]: info: CoreCommandRouter::volumioPushQueue Sep 11 01:16:06 opera volumio[1423]: info: CoreStateMachine::addQueueItems Sep 11 01:16:06 opera volumio[1423]: info: CorePlayQueue::addQueueItems Sep 11 01:16:06 opera volumio[1423]: info: Adding Item to queue: music-library/USB/4DSD600/FIM一听钟情25张经典唱片/FIM SACD 051 最浪漫电影主题曲 - 总有一天【Ilja Warenberg - A Time For Us】 Sep 11 01:16:06 opera volumio[1423]: info: Exploding uri music-library/USB/4DSD600/FIM一听钟情25张经典唱片/FIM SACD 051 最浪漫电影主题曲 - 总有一天【Ilja Warenberg - A Time For Us】 in service mpd Sep 11 01:16:06 opera volumio[1423]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 11 01:16:06 opera volumio[1423]: info: Updating RAAT Signal Path Sep 11 01:16:06 opera volumio[1423]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 11 01:16:06 opera volumio[1423]: info: ALBUMART /albumart?cacheid=382&web=Alexander%20Warenberg%20and%20His%20Orchestra%2C%20Ilja%20Warenberg/A%20Time%20For%20Us/small&path=%2Fmnt%2FUSB%2F4DSD600%2FFIM%E4%B8%80%E5%90%AC%E9%92%9F%E6%83%8525%E5%BC%A0%E7%BB%8F%E5%85%B8%E5%94%B1%E7%89%87%2FFIM%20SACD%20051%20%E6%9C%80%E6%B5%AA%E6%BC%AB%E7%94%B5%E5%BD%B1%E4%B8%BB%E9%A2%98%E6%9B%B2%20-%20%E6%80%BB%E6%9C%89%E4%B8%80%E5%A4%A9%E3%80%90Ilja%20Warenberg%20-%20A%20Time%20For%20Us%E3%80%91%2F01-01.%20Alexander%20Warenberg%20and%20His%20Orchestra%2C%20Ilja%20Warenberg%20-%20A%20Time%20For%20Us.dsf&metadata=false Sep 11 01:16:06 opera volumio[1423]: info: URI /mnt/USB/4DSD600/FIM一听钟情25张经典唱片/FIM SACD 051 最浪漫电影主题曲 - 总有一天【Ilja Warenberg - A Time For Us】/01-01. Alexander Warenberg and His Orchestra, Ilja Warenberg - A Time For Us.dsf Sep 11 01:16:06 opera volumio[1423]: info: ALBUMART /albumart?cacheid=382&web=Alexander%20Warenberg%20and%20His%20Orchestra%2C%20Ilja%20Warenberg/A%20Time%20For%20Us/small&path=%2Fmnt%2FUSB%2F4DSD600%2FFIM%E4%B8%80%E5%90%AC%E9%92%9F%E6%83%8525%E5%BC%A0%E7%BB%8F%E5%85%B8%E5%94%B1%E7%89%87%2FFIM%20SACD%20051%20%E6%9C%80%E6%B5%AA%E6%BC%AB%E7%94%B5%E5%BD%B1%E4%B8%BB%E9%A2%98%E6%9B%B2%20-%20%E6%80%BB%E6%9C%89%E4%B8%80%E5%A4%A9%E3%80%90Ilja%20Warenberg%20-%20A%20Time%20For%20Us%E3%80%91%2F01-02.%20Alexander%20Warenberg%20and%20His%20Orchestra%2C%20Ilja%20Warenberg%20-%20Old%20Melody.dsf&metadata=false Sep 11 01:16:06 opera volumio[1423]: info: URI /mnt/USB/4DSD600/FIM一听钟情25张经典唱片/FIM SACD 051 最浪漫电影主题曲 - 总有一天【Ilja Warenberg - A Time For Us】/01-02. Alexander Warenberg and His Orchestra, Ilja Warenberg - Old Melody.dsf Sep 11 01:16:06 opera volumio[1423]: info: ALBUMART /albumart?cacheid=382&web=Alexander%20Warenberg%20and%20His%20Orchestra%2C%20Ilja%20Warenberg/A%20Time%20For%20Us/small&path=%2Fmnt%2FUSB%2F4DSD600%2FFIM%E4%B8%80%E5%90%AC%E9%92%9F%E6%83%8525%E5%BC%A0%E7%BB%8F%E5%85%B8%E5%94%B1%E7%89%87%2FFIM%20SACD%20051%20%E6%9C%80%E6%B5%AA%E6%BC%AB%E7%94%B5%E5%BD%B1%E4%B8%BB%E9%A2%98%E6%9B%B2%20-%20%E6%80%BB%E6%9C%89%E4%B8%80%E5%A4%A9%E3%80%90Ilja%20Warenberg%20-%20A%20Time%20For%20Us%E3%80%91%2F01-03.%20Alexander%20Warenberg%20and%20His%20Orchestra%2C%20Ilja%20Warenberg%20-%20O%20mio%20Babbino%20Caro.dsf&metadata=false Sep 11 01:16:06 opera volumio[1423]: info: URI /mnt/USB/4DSD600/FIM一听钟情25张经典唱片/FIM SACD 051 最浪漫电影主题曲 - 总有一天【Ilja Warenberg - A Time For Us】/01-03. Alexander Warenberg and His Orchestra, Ilja Warenberg - O mio Babbino Caro.dsf Sep 11 01:16:06 opera volumio[1423]: info: ALBUMART /albumart?cacheid=382&web=Alexander%20Warenberg%20and%20His%20Orchestra%2C%20Ilja%20Warenberg/A%20Time%20For%20Us/small&path=%2Fmnt%2FUSB%2F4DSD600%2FFIM%E4%B8%80%E5%90%AC%E9%92%9F%E6%83%8525%E5%BC%A0%E7%BB%8F%E5%85%B8%E5%94%B1%E7%89%87%2FFIM%20SACD%20051%20%E6%9C%80%E6%B5%AA%E6%BC%AB%E7%94%B5%E5%BD%B1%E4%B8%BB%E9%A2%98%E6%9B%B2%20-%20%E6%80%BB%E6%9C%89%E4%B8%80%E5%A4%A9%E3%80%90Ilja%20Warenberg%20-%20A%20Time%20For%20Us%E3%80%91%2F01-04.%20Alexander%20Warenberg%20and%20His%20Orchestra%2C%20Ilja%20Warenberg%20-%20Clarinet%20Concerto.dsf&metadata=false Sep 11 01:16:06 opera volumio[1423]: info: URI /mnt/USB/4DSD600/FIM一听钟情25张经典唱片/FIM SACD 051 最浪漫电影主题曲 - 总有一天【Ilja Warenberg - A Time For Us】/01-04. Alexander Warenberg and His Orchestra, Ilja Warenberg - Clarinet Concerto.dsf Sep 11 01:16:06 opera volumio[1423]: info: ALBUMART /albumart?cacheid=382&web=Alexander%20Warenberg%20and%20His%20Orchestra%2C%20Ilja%20Warenberg/A%20Time%20For%20Us/small&path=%2Fmnt%2FUSB%2F4DSD600%2FFIM%E4%B8%80%E5%90%AC%E9%92%9F%E6%83%8525%E5%BC%A0%E7%BB%8F%E5%85%B8%E5%94%B1%E7%89%87%2FFIM%20SACD%20051%20%E6%9C%80%E6%B5%AA%E6%BC%AB%E7%94%B5%E5%BD%B1%E4%B8%BB%E9%A2%98%E6%9B%B2%20-%20%E6%80%BB%E6%9C%89%E4%B8%80%E5%A4%A9%E3%80%90Ilja%20Warenberg%20-%20A%20Time%20For%20Us%E3%80%91%2F01-05.%20Alexander%20Warenberg%20and%20His%20Orchestra%2C%20Ilja%20Warenberg%20-%20Maria.dsf&metadata=false Sep 11 01:16:06 opera volumio[1423]: info: URI /mnt/USB/4DSD600/FIM一听钟情25张经典唱片/FIM SACD 051 最浪漫电影主题曲 - 总有一天【Ilja Warenberg - A Time For Us】/01-05. Alexander Warenberg and His Orchestra, Ilja Warenberg - Maria.dsf Sep 11 01:16:06 opera volumio[1423]: info: ALBUMART /albumart?cacheid=382&web=Alexander%20Warenberg%20and%20His%20Orchestra%2C%20Ilja%20Warenberg/A%20Time%20For%20Us/small&path=%2Fmnt%2FUSB%2F4DSD600%2FFIM%E4%B8%80%E5%90%AC%E9%92%9F%E6%83%8525%E5%BC%A0%E7%BB%8F%E5%85%B8%E5%94%B1%E7%89%87%2FFIM%20SACD%20051%20%E6%9C%80%E6%B5%AA%E6%BC%AB%E7%94%B5%E5%BD%B1%E4%B8%BB%E9%A2%98%E6%9B%B2%20-%20%E6%80%BB%E6%9C%89%E4%B8%80%E5%A4%A9%E3%80%90Ilja%20Warenberg%20-%20A%20Time%20For%20Us%E3%80%91%2F01-06.%20Alexander%20Warenberg%20and%20His%20Orchestra%2C%20Ilja%20Warenberg%20-%20Angelique.dsf&metadata=false Sep 11 01:16:06 opera volumio[1423]: info: URI /mnt/USB/4DSD600/FIM一听钟情25张经典唱片/FIM SACD 051 最浪漫电影主题曲 - 总有一天【Ilja Warenberg - A Time For Us】/01-06. Alexander Warenberg and His Orchestra, Ilja Warenberg - Angelique.dsf Sep 11 01:16:06 opera volumio[1423]: info: ALBUMART /albumart?cacheid=382&web=Alexander%20Warenberg%20and%20His%20Orchestra%2C%20Ilja%20Warenberg/A%20Time%20For%20Us/small&path=%2Fmnt%2FUSB%2F4DSD600%2FFIM%E4%B8%80%E5%90%AC%E9%92%9F%E6%83%8525%E5%BC%A0%E7%BB%8F%E5%85%B8%E5%94%B1%E7%89%87%2FFIM%20SACD%20051%20%E6%9C%80%E6%B5%AA%E6%BC%AB%E7%94%B5%E5%BD%B1%E4%B8%BB%E9%A2%98%E6%9B%B2%20-%20%E6%80%BB%E6%9C%89%E4%B8%80%E5%A4%A9%E3%80%90Ilja%20Warenberg%20-%20A%20Time%20For%20Us%E3%80%91%2F01-07.%20Alexander%20Warenberg%20and%20His%20Orchestra%2C%20Ilja%20Warenberg%20-%20Once%20Upon%20A%20Time%20in%20America.dsf&metadata=false Sep 11 01:16:06 opera volumio[1423]: info: URI /mnt/USB/4DSD600/FIM一听钟情25张经典唱片/FIM SACD 051 最浪漫电影主题曲 - 总有一天【Ilja Warenberg - A Time For Us】/01-07. Alexander Warenberg and His Orchestra, Ilja Warenberg - Once Upon A Time in America.dsf Sep 11 01:16:06 opera volumio[1423]: info: ALBUMART /albumart?cacheid=382&web=Alexander%20Warenberg%20and%20His%20Orchestra%2C%20Ilja%20Warenberg/A%20Time%20For%20Us/small&path=%2Fmnt%2FUSB%2F4DSD600%2FFIM%E4%B8%80%E5%90%AC%E9%92%9F%E6%83%8525%E5%BC%A0%E7%BB%8F%E5%85%B8%E5%94%B1%E7%89%87%2FFIM%20SACD%20051%20%E6%9C%80%E6%B5%AA%E6%BC%AB%E7%94%B5%E5%BD%B1%E4%B8%BB%E9%A2%98%E6%9B%B2%20-%20%E6%80%BB%E6%9C%89%E4%B8%80%E5%A4%A9%E3%80%90Ilja%20Warenberg%20-%20A%20Time%20For%20Us%E3%80%91%2F01-08.%20Alexander%20Warenberg%20and%20His%20Orchestra%2C%20Ilja%20Warenberg%20-%20Smile.dsf&metadata=false Sep 11 01:16:06 opera volumio[1423]: info: URI /mnt/USB/4DSD600/FIM一听钟情25张经典唱片/FIM SACD 051 最浪漫电影主题曲 - 总有一天【Ilja Warenberg - A Time For Us】/01-08. Alexander Warenberg and His Orchestra, Ilja Warenberg - Smile.dsf Sep 11 01:16:06 opera volumio[1423]: info: ALBUMART /albumart?cacheid=382&web=Alexander%20Warenberg%20and%20His%20Orchestra%2C%20Ilja%20Warenberg/A%20Time%20For%20Us/small&path=%2Fmnt%2FUSB%2F4DSD600%2FFIM%E4%B8%80%E5%90%AC%E9%92%9F%E6%83%8525%E5%BC%A0%E7%BB%8F%E5%85%B8%E5%94%B1%E7%89%87%2FFIM%20SACD%20051%20%E6%9C%80%E6%B5%AA%E6%BC%AB%E7%94%B5%E5%BD%B1%E4%B8%BB%E9%A2%98%E6%9B%B2%20-%20%E6%80%BB%E6%9C%89%E4%B8%80%E5%A4%A9%E3%80%90Ilja%20Warenberg%20-%20A%20Time%20For%20Us%E3%80%91%2F01-09.%20Alexander%20Warenberg%20and%20His%20Orchestra%2C%20Ilja%20Warenberg%20-%20Spartacus.dsf&metadata=false Sep 11 01:16:06 opera volumio[1423]: info: URI /mnt/USB/4DSD600/FIM一听钟情25张经典唱片/FIM SACD 051 最浪漫电影主题曲 - 总有一天【Ilja Warenberg - A Time For Us】/01-09. Alexander Warenberg and His Orchestra, Ilja Warenberg - Spartacus.dsf Sep 11 01:16:06 opera volumio[1423]: info: ALBUMART /albumart?cacheid=382&web=Alexander%20Warenberg%20and%20His%20Orchestra%2C%20Ilja%20Warenberg/A%20Time%20For%20Us/small&path=%2Fmnt%2FUSB%2F4DSD600%2FFIM%E4%B8%80%E5%90%AC%E9%92%9F%E6%83%8525%E5%BC%A0%E7%BB%8F%E5%85%B8%E5%94%B1%E7%89%87%2FFIM%20SACD%20051%20%E6%9C%80%E6%B5%AA%E6%BC%AB%E7%94%B5%E5%BD%B1%E4%B8%BB%E9%A2%98%E6%9B%B2%20-%20%E6%80%BB%E6%9C%89%E4%B8%80%E5%A4%A9%E3%80%90Ilja%20Warenberg%20-%20A%20Time%20For%20Us%E3%80%91%2F01-10.%20Alexander%20Warenberg%20and%20His%20Orchestra%2C%20Ilja%20Warenberg%20-%20Schindler's%20List.dsf&metadata=false Sep 11 01:16:06 opera volumio[1423]: info: URI /mnt/USB/4DSD600/FIM一听钟情25张经典唱片/FIM SACD 051 最浪漫电影主题曲 - 总有一天【Ilja Warenberg - A Time For Us】/01-10. Alexander Warenberg and His Orchestra, Ilja Warenberg - Schindler's List.dsf Sep 11 01:16:06 opera volumio[1423]: info: ALBUMART /albumart?cacheid=382&web=Alexander%20Warenberg%20and%20His%20Orchestra%2C%20Ilja%20Warenberg/A%20Time%20For%20Us/small&path=%2Fmnt%2FUSB%2F4DSD600%2FFIM%E4%B8%80%E5%90%AC%E9%92%9F%E6%83%8525%E5%BC%A0%E7%BB%8F%E5%85%B8%E5%94%B1%E7%89%87%2FFIM%20SACD%20051%20%E6%9C%80%E6%B5%AA%E6%BC%AB%E7%94%B5%E5%BD%B1%E4%B8%BB%E9%A2%98%E6%9B%B2%20-%20%E6%80%BB%E6%9C%89%E4%B8%80%E5%A4%A9%E3%80%90Ilja%20Warenberg%20-%20A%20Time%20For%20Us%E3%80%91%2F01-11.%20Alexander%20Warenberg%20and%20His%20Orchestra%2C%20Ilja%20Warenberg%20-%20Adagio.dsf&metadata=false Sep 11 01:16:06 opera volumio[1423]: info: URI /mnt/USB/4DSD600/FIM一听钟情25张经典唱片/FIM SACD 051 最浪漫电影主题曲 - 总有一天【Ilja Warenberg - A Time For Us】/01-11. Alexander Warenberg and His Orchestra, Ilja Warenberg - Adagio.dsf Sep 11 01:16:06 opera volumio[1423]: info: CorePlayQueue::saveQueue Sep 11 01:16:06 opera volumio[1423]: info: CoreCommandRouter::volumioPushQueue Sep 11 01:16:06 opera volumio[1423]: info: CoreStateMachine::updateTrackBlock Sep 11 01:16:06 opera volumio[1423]: info: CorePlayQueue::getTrackBlock Sep 11 01:16:06 opera volumio[1423]: info: CoreCommandRouter::volumioPlay Sep 11 01:16:06 opera volumio[1423]: verbose: UNSET VOLATILE Sep 11 01:16:06 opera volumio[1423]: info: CoreStateMachine::play index 0 Sep 11 01:16:06 opera volumio[1423]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 01:16:06 opera volumio[1423]: info: CoreStateMachine::stop Sep 11 01:16:06 opera volumio[1423]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 01:16:06 opera volumio[1423]: verbose: UNSET VOLATILE Sep 11 01:16:06 opera volumio[1423]: info: CoreStateMachine::play index undefined Sep 11 01:16:06 opera volumio[1423]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 01:16:06 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:16:06 opera volumio[1423]: info: CoreStateMachine::startPlaybackTimer Sep 11 01:16:06 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:16:06 opera volumio[1423]: verbose: ControllerMpd::clearAddPlayTracks USB/4DSD600/FIM一听钟情25张经典唱片/FIM SACD 051 最浪漫电影主题曲 - 总有一天【Ilja Warenberg - A Time For Us】/01-01. Alexander Warenberg and His Orchestra, Ilja Warenberg - A Time For Us.dsf Sep 11 01:16:06 opera volumio[1423]: verbose: ControllerMpd::sendMpdCommand stop Sep 11 01:16:06 opera volumio[1423]: info: sendMpdCommand stop took 6 milliseconds Sep 11 01:16:06 opera volumio[1423]: verbose: ControllerMpd::sendMpdCommand clear Sep 11 01:16:06 opera volumio[1423]: info: Sep 11 01:16:06 opera volumio[1423]: ---------------------------- MPD announces system playlist update Sep 11 01:16:06 opera volumio[1423]: info: Ignoring MPD Status Update Sep 11 01:16:06 opera volumio[1423]: info: sendMpdCommand clear took 20 milliseconds Sep 11 01:16:06 opera volumio[1423]: verbose: ControllerMpd::sendMpdCommand add "USB/4DSD600/FIM一听钟情25张经典唱片/FIM SACD 051 最浪漫电影主题曲 - 总有一天【Ilja Warenberg - A Time For Us】/01-01. Alexander Warenberg and His Orchestra, Ilja Warenberg - A Time For Us.dsf" Sep 11 01:16:06 opera volumio[1423]: error: updateQueue error: null Sep 11 01:16:06 opera volumio[1423]: info: Sep 11 01:16:06 opera volumio[1423]: ---------------------------- MPD announces system playlist update Sep 11 01:16:06 opera volumio[1423]: info: Ignoring MPD Status Update Sep 11 01:16:06 opera volumio[1423]: info: ------------------------------ 13ms Sep 11 01:16:06 opera volumio[1423]: info: sendMpdCommand add "USB/4DSD600/FIM一听钟情25张经典唱片/FIM SACD 051 最浪漫电影主题曲 - 总有一天【Ilja Warenberg - A Time For Us】/01-01. Alexander Warenberg and His Orchestra, Ilja Warenberg - A Time For Us.dsf" took 12 milliseconds Sep 11 01:16:06 opera volumio[1423]: verbose: ControllerMpd::sendMpdCommand play Sep 11 01:16:06 opera volumio[1423]: info: ------------------------------ 10ms Sep 11 01:16:06 opera volumio[1423]: info: sendMpdCommand play took 9 milliseconds Sep 11 01:16:06 opera volumio[1423]: info: Sep 11 01:16:06 opera volumio[1423]: ---------------------------- MPD announces state update: player Sep 11 01:16:06 opera volumio[1423]: info: ControllerMpd::getState Sep 11 01:16:06 opera volumio[1423]: verbose: ControllerMpd::sendMpdCommand status Sep 11 01:16:06 opera volumio[1423]: info: Sep 11 01:16:06 opera volumio[1423]: ---------------------------- MPD announces state update: player Sep 11 01:16:06 opera volumio[1423]: info: sendMpdCommand status took 25 milliseconds Sep 11 01:16:06 opera volumio[1423]: info: ControllerMpd::getState Sep 11 01:16:06 opera volumio[1423]: verbose: ControllerMpd::sendMpdCommand status Sep 11 01:16:06 opera volumio[1423]: verbose: ControllerMpd::parseState Sep 11 01:16:06 opera volumio[1423]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 01:16:06 opera volumio[1423]: info: sendMpdCommand status took 3 milliseconds Sep 11 01:16:06 opera volumio[1423]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 11 01:16:06 opera volumio[1423]: verbose: ControllerMpd::parseState Sep 11 01:16:06 opera volumio[1423]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 01:16:06 opera volumio[1423]: verbose: ControllerMpd::parseTrackInfo Sep 11 01:16:06 opera volumio[1423]: info: ControllerMpd::pushState Sep 11 01:16:06 opera volumio[1423]: info: CoreCommandRouter::servicePushState Sep 11 01:16:06 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:16:06 opera volumio[1423]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"A Time For Us","artist":"Alexander Warenberg and His Orchestra, Ilja Warenberg","album":"A Time For Us","uri":"USB/4DSD600/FIM一听钟情25张经典唱片/FIM SACD 051 最浪漫电影主题曲 - 总有一天【Ilja Warenberg - A Time For Us】/01-01. Alexander Warenberg and His Orchestra, Ilja Warenberg - A Time For Us.dsf","trackType":"dsf"} Sep 11 01:16:06 opera volumio[1423]: verbose: CURRENT POSITION 0 Sep 11 01:16:06 opera volumio[1423]: info: CoreStateMachine::syncState stateService play Sep 11 01:16:06 opera volumio[1423]: info: CoreStateMachine::syncState currentStatus stop Sep 11 01:16:06 opera volumio[1423]: info: CoreStateMachine::pushState Sep 11 01:16:06 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:16:06 opera volumio[1423]: info: CoreCommandRouter::volumioPushState Sep 11 01:16:06 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 01:16:07 opera volumio[1423]: info: ------------------------------ 54ms Sep 11 01:16:07 opera volumio[1423]: info: sendMpdCommand playlistinfo took 24 milliseconds Sep 11 01:16:07 opera volumio[1423]: verbose: ControllerMpd::parseTrackInfo Sep 11 01:16:07 opera volumio[1423]: info: ControllerMpd::pushState Sep 11 01:16:07 opera volumio[1423]: info: CoreCommandRouter::servicePushState Sep 11 01:16:07 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:16:07 opera volumio[1423]: verbose: STATE SERVICE {"status":"play","position":0,"seek":493,"duration":200,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"A Time For Us","artist":"Alexander Warenberg and His Orchestra, Ilja Warenberg","album":"A Time For Us","uri":"USB/4DSD600/FIM一听钟情25张经典唱片/FIM SACD 051 最浪漫电影主题曲 - 总有一天【Ilja Warenberg - A Time For Us】/01-01. Alexander Warenberg and His Orchestra, Ilja Warenberg - A Time For Us.dsf","trackType":"dsf"} Sep 11 01:16:07 opera volumio[1423]: verbose: CURRENT POSITION 0 Sep 11 01:16:07 opera volumio[1423]: info: CoreStateMachine::syncState stateService play Sep 11 01:16:07 opera volumio[1423]: info: CoreStateMachine::syncState currentStatus play Sep 11 01:16:07 opera volumio[1423]: info: Received an update from plugin. extracting info from payload Sep 11 01:16:07 opera volumio[1423]: info: CoreStateMachine::pushState Sep 11 01:16:07 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:16:07 opera volumio[1423]: info: CoreCommandRouter::volumioPushState Sep 11 01:16:07 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 01:16:07 opera volumio[1423]: info: CoreStateMachine::pushState Sep 11 01:16:07 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:16:07 opera volumio[1423]: info: CoreCommandRouter::volumioPushState Sep 11 01:16:07 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 01:16:07 opera volumio[1423]: info: ------------------------------ 51ms Sep 11 01:16:07 opera volumio[1423]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 11 01:16:07 opera volumio[1423]: info: Updating RAAT Signal Path Sep 11 01:16:07 opera volumio[1423]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 11 01:16:07 opera volumio[1423]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 11 01:16:07 opera volumio[1423]: info: Updating RAAT Signal Path Sep 11 01:16:07 opera volumio[1423]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 11 01:16:07 opera volumio[1423]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 11 01:16:07 opera volumio[1423]: info: Updating RAAT Signal Path Sep 11 01:16:07 opera volumio[1423]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 11 01:16:17 opera volumio[1423]: verbose: ControllerMpd::sendMpdCommand status Sep 11 01:16:17 opera volumio[1423]: info: Sep 11 01:16:17 opera volumio[1423]: ---------------------------- MPD announces state update: update Sep 11 01:16:17 opera volumio[1423]: info: ControllerMpd::getState Sep 11 01:16:17 opera volumio[1423]: verbose: ControllerMpd::sendMpdCommand status Sep 11 01:16:17 opera volumio[1423]: info: sendMpdCommand status took 3 milliseconds Sep 11 01:16:17 opera volumio[1423]: info: sendMpdCommand status took 2 milliseconds Sep 11 01:16:17 opera volumio[1423]: verbose: ControllerMpd::parseState Sep 11 01:16:17 opera volumio[1423]: info: Command Router : Notfying DB Updatefalse Sep 11 01:16:17 opera volumio[1423]: info: CoreCommandRouter::Close All Modals sent Sep 11 01:16:17 opera volumio[1423]: verbose: ControllerMpd::parseState Sep 11 01:16:17 opera volumio[1423]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 01:16:17 opera volumio[1423]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 11 01:16:17 opera volumio[1423]: verbose: ControllerMpd::parseTrackInfo Sep 11 01:16:17 opera volumio[1423]: info: ControllerMpd::pushState Sep 11 01:16:17 opera volumio[1423]: info: CoreCommandRouter::servicePushState Sep 11 01:16:17 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:16:17 opera volumio[1423]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10988,"duration":200,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"A Time For Us","artist":"Alexander Warenberg and His Orchestra, Ilja Warenberg","album":"A Time For Us","uri":"USB/4DSD600/FIM一听钟情25张经典唱片/FIM SACD 051 最浪漫电影主题曲 - 总有一天【Ilja Warenberg - A Time For Us】/01-01. Alexander Warenberg and His Orchestra, Ilja Warenberg - A Time For Us.dsf","trackType":"dsf"} Sep 11 01:16:17 opera volumio[1423]: verbose: CURRENT POSITION 0 Sep 11 01:16:17 opera volumio[1423]: info: CoreStateMachine::syncState stateService play Sep 11 01:16:17 opera volumio[1423]: info: CoreStateMachine::syncState currentStatus play Sep 11 01:16:17 opera volumio[1423]: info: Received an update from plugin. extracting info from payload Sep 11 01:16:17 opera volumio[1423]: info: CoreStateMachine::pushState Sep 11 01:16:17 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:16:17 opera volumio[1423]: info: CoreCommandRouter::volumioPushState Sep 11 01:16:17 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 01:16:17 opera volumio[1423]: info: CoreStateMachine::pushState Sep 11 01:16:17 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:16:17 opera volumio[1423]: info: CoreCommandRouter::volumioPushState Sep 11 01:16:17 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 01:16:17 opera volumio[1423]: info: ------------------------------ 72ms Sep 11 01:16:17 opera volumio[1423]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 11 01:16:17 opera volumio[1423]: info: Updating RAAT Signal Path Sep 11 01:16:17 opera volumio[1423]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 11 01:16:17 opera volumio[1423]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 11 01:16:17 opera volumio[1423]: info: Updating RAAT Signal Path Sep 11 01:16:17 opera volumio[1423]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 11 01:16:17 opera volumio[1905]: [Vollibrespot] : couldn't parse packet from V4(192.168.1.10:5353): query type 65 is invalid Sep 11 01:16:18 opera volumio[1905]: [Vollibrespot] : couldn't parse packet from V4(192.168.1.10:5353): query type 65 is invalid Sep 11 01:16:23 opera systemd[1]: Started volumio-remote-updater-push.service. Sep 11 01:16:23 opera systemd[1]: Starting Multi-User System. Sep 11 01:16:23 opera systemd[1]: Reached target Multi-User System. Sep 11 01:16:23 opera systemd[1]: Starting Graphical Interface. Sep 11 01:16:23 opera systemd[1]: Reached target Graphical Interface. Sep 11 01:16:23 opera systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 11 01:16:23 opera volumio[1423]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 11 01:16:23 opera systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 11 01:16:23 opera systemd[1]: Startup finished in 11.837s (kernel) + 2min 5.132s (userspace) = 2min 16.970s. Sep 11 01:16:32 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 11 01:16:32 opera volumio[1423]: info: CURURI: music-library/USB/4DSD600/[SACD.ISO] Sep 11 01:16:38 opera volumio[1423]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 11 01:16:38 opera volumio[1423]: info: CoreStateMachine::ClearQueue Sep 11 01:16:38 opera volumio[1423]: info: CoreStateMachine::stop Sep 11 01:16:38 opera volumio[1423]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 01:16:38 opera volumio[1423]: verbose: UNSET VOLATILE Sep 11 01:16:38 opera volumio[1423]: info: CoreStateMachine::stPlaybackTimer Sep 11 01:16:38 opera volumio[1423]: info: CoreStateMachine::updateTrackBlock Sep 11 01:16:38 opera volumio[1423]: info: CorePlayQueue::getTrackBlock Sep 11 01:16:38 opera volumio[1423]: info: CoreStateMachine::pushState Sep 11 01:16:38 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:16:38 opera volumio[1423]: info: CoreCommandRouter::volumioPushState Sep 11 01:16:38 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 01:16:38 opera volumio[1423]: info: CoreStateMachine::serviceStop Sep 11 01:16:38 opera volumio[1423]: info: CorePlayQueue::getTrack 0 Sep 11 01:16:38 opera volumio[1423]: info: CoreCommandRouter::serviceStop Sep 11 01:16:38 opera volumio[1423]: info: ControllerMpd::stop Sep 11 01:16:38 opera volumio[1423]: verbose: ControllerMpd::sendMpdCommand stop Sep 11 01:16:38 opera volumio[1423]: info: CorePlayQueue::clearPlayQueue Sep 11 01:16:38 opera volumio[1423]: info: CorePlayQueue::saveQueue Sep 11 01:16:38 opera volumio[1423]: info: CoreCommandRouter::volumioPushState Sep 11 01:16:38 opera volumio[1423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 01:16:38 opera volumio[1423]: info: CoreCommandRouter::volumioPushQueue Sep 11 01:16:38 opera volumio[1423]: info: CoreStateMachine::addQueueItems Sep 11 01:16:38 opera volumio[1423]: info: CorePlayQueue::addQueueItems Sep 11 01:16:38 opera volumio[1423]: info: Adding Item to queue: music-library/USB/4DSD600/[SACD.ISO]/[DSD-DSF]齐秦（狼I）《黄金自选辑》 Sep 11 01:16:38 opera volumio[1423]: info: Exploding uri music-library/USB/4DSD600/[SACD.ISO]/[DSD-DSF]齐秦（狼I）《黄金自选辑》 in service mpd Sep 11 01:16:38 opera volumio[1423]: info: Sep 11 01:16:38 opera volumio[1423]: ---------------------------- MPD announces state update: player Sep 11 01:16:38 opera volumio[1423]: info: ALBUMART /albumart?cacheid=382&web=//small&path=%2Fmnt%2FUSB%2F4DSD600%2F%5BSACD.ISO%5D%2F%5BDSD-DSF%5D%E9%BD%90%E7%A7%A6%EF%BC%88%E7%8B%BCI%EF%BC%89%E3%80%8A%E9%BB%84%E9%87%91%E8%87%AA%E9%80%89%E8%BE%91%E3%80%8B&metadata=false Sep 11 01:16:38 opera volumio[1423]: info: URI /mnt/USB/4DSD600/[SACD.ISO]/[DSD-DSF]齐秦（狼I）《黄金自选辑》 Sep 11 01:16:38 opera volumio[1423]: info: ALBUMART /albumart?cacheid=382&web=//small&path=%2Fmnt%2FUSB%2F4DSD600%2F%5BSACD.ISO%5D%2F%5BDSD-DSF%5D%E9%BD%90%E7%A7%A6%EF%BC%88%E7%8B%BCI%EF%BC%89%E3%80%8A%E9%BB%84%E9%87%91%E8%87%AA%E9%80%89%E8%BE%91%E3%80%8B&metadata=false Sep 11 01:16:38 opera volumio[1423]: info: URI /mnt/USB/4DSD600/[SACD.ISO]/[DSD-DSF]齐秦（狼I）《黄金自选辑》 Sep 11 01:16:38 opera volumio[1423]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 11 01:16:38 opera volumio[1423]: Error: Unable to resolve or reject the same promise twice Sep 11 01:16:38 opera volumio[1423]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Sep 11 01:16:38 opera volumio[1423]: at /volumio/app/plugins/music_service/mpd/index.js:2520:21 Sep 11 01:16:38 opera volumio[1423]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Sep 11 01:16:38 opera volumio[1423]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Sep 11 01:16:38 opera volumio[1423]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Sep 11 01:16:38 opera volumio[1423]: at emitOne (events.js:116:13) Sep 11 01:16:38 opera volumio[1423]: at Socket.emit (events.js:211:7) Sep 11 01:16:38 opera volumio[1423]: at addChunk (_stream_readable.js:263:12) Sep 11 01:16:38 opera volumio[1423]: at readableAddChunk (_stream_readable.js:246:13) Sep 11 01:16:38 opera volumio[1423]: at Socket.Readable.push (_stream_readable.js:208:10) Sep 11 01:16:38 opera volumio[1423]: at Pipe.onread (net.js:607:20) Sep 11 01:16:38 opera volumio[1423]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 11 01:16:38 opera sudo[2279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-11 01:15 Sep 11 01:16:38 opera sudo[2279]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="047a38c6e11e7058b10d3038ac9dcbff94f92828" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="opera" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Apr 8 17:29:57 CEST 2022" VOLUMIO_VERSION="1.033" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_VENDOR="Opera Consonance" VOLUMIO_MODEL="X5" VOLUMIO_VENDOR_MODEL="Opera X5" VOLUMIO_HASH="77fa3b0bf86939c934741dd9bb62adcc"