-- Logs begin at Thu 2025-05-15 06:53:52 UTC, end at Thu 2025-05-15 06:59:22 UTC. -- May 15 06:58:01 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 06:58:05 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 06:58:07 opera dhcpd[1414]: DHCPDISCOVER from 48:e7:da:4e:fa:c3 (opera) via wlan0 May 15 06:58:08 opera dhcpd[1414]: DHCPOFFER on 192.168.211.6 to 48:e7:da:4e:fa:c3 (opera) via wlan0 May 15 06:58:09 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 06:58:10 opera volumio[1386]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 06:58:10 opera volumio[1386]: No protocol specified May 15 06:58:10 opera volumio[1386]: xcb_connection_has_error() returned true May 15 06:58:10 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 15 06:58:10 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 06:58:10 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 15 06:58:10 opera volumio[1386]: No protocol specified May 15 06:58:10 opera volumio[1386]: xcb_connection_has_error() returned true May 15 06:58:10 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 06:58:10 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 06:58:10 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 06:58:10 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 06:58:10 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 06:58:10 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 06:58:10 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 06:58:10 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 15 06:58:10 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 15 06:58:10 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection May 15 06:58:10 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 06:58:10 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 06:58:20 opera volumio[1386]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::volumioGetState May 15 06:58:20 opera volumio[1386]: info: CorePlayQueue::getTrack 0 May 15 06:58:20 opera volumio[1386]: info: Restoring Previous Volume level: 85 false false May 15 06:58:20 opera volumio[1386]: info: Volume configurations have been set May 15 06:58:20 opera volumio[1386]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes May 15 06:58:20 opera volumio[1386]: No protocol specified May 15 06:58:20 opera volumio[1386]: xcb_connection_has_error() returned true May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 15 06:58:20 opera volumio[1386]: info: Updating Volume Controller Parameters: Device: 5 Name: Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::volumioGetState May 15 06:58:20 opera volumio[1386]: info: CorePlayQueue::getTrack 0 May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 06:58:20 opera volumio[1386]: No protocol specified May 15 06:58:20 opera volumio[1386]: xcb_connection_has_error() returned true May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 15 06:58:20 opera volumio[1386]: No protocol specified May 15 06:58:20 opera volumio[1386]: xcb_connection_has_error() returned true May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection May 15 06:58:20 opera volumio[1386]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified May 15 06:58:20 opera volumio[1386]: xcb_connection_has_error() returned true May 15 06:58:20 opera volumio[1386]: [SpotifyConnect] Restarting Vollibrespot Daemon May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 06:58:20 opera volumio[1386]: info: VolumeController:: Volume=undefined Mute =false May 15 06:58:20 opera volumio[1386]: info: CoreStateMachine::pushState May 15 06:58:20 opera sudo[2572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service May 15 06:58:20 opera volumio[1386]: info: CorePlayQueue::getTrack 0 May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::volumioPushState May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 06:58:20 opera sudo[2572]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 06:58:20 opera volumio[1386]: info: Updating RAAT Signal Path May 15 06:58:20 opera systemd[1]: Stopping Volspotconnect2 Daemon... May 15 06:58:20 opera systemd[1]: Starting Volspotconnect2 Daemon... May 15 06:58:20 opera systemd[1]: Started Volspotconnect2 Daemon. May 15 06:58:20 opera sudo[2572]: pam_unix(sudo:session): session closed for user root May 15 06:58:20 opera volumio[1386]: ------------------------------------ BT MESSAGE: BT STATUS: running May 15 06:58:20 opera volumio[1386]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 15 06:58:20 opera volumio[1386]: [SpotifyConnect] null May 15 06:58:20 opera volumio[2578]: vollibrespot v0.1.9 c98fd5d 2019-11-08 (librespot 4674946 2019-11-08) -- Built On 2019-11-08 May 15 06:58:20 opera sudo[2587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service May 15 06:58:20 opera sudo[2587]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 06:58:20 opera systemd[1]: Started Volspotconnect2 Daemon. May 15 06:58:20 opera sudo[2587]: pam_unix(sudo:session): session closed for user root May 15 06:58:20 opera volumio[1386]: [SpotifyConnect] Vollibrespot Daemon Started May 15 06:58:20 opera volumio[2578]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 06:58:20 opera volumio[1386]: info: Not Reporting Auto name since its the default one May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable May 15 06:58:20 opera volumio[1386]: No protocol specified May 15 06:58:20 opera volumio[1386]: xcb_connection_has_error() returned true May 15 06:58:20 opera sudo[2608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service May 15 06:58:20 opera sudo[2608]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 06:58:20 opera systemd[1]: Stopping RAAT DAEMON... May 15 06:58:20 opera volumio[1386]: ------------------------------------ BT MESSAGE: BT STATUS: running May 15 06:58:20 opera systemd[1]: Starting RAAT DAEMON... May 15 06:58:20 opera systemd[1]: Started RAAT DAEMON. May 15 06:58:20 opera sudo[2608]: pam_unix(sudo:session): session closed for user root May 15 06:58:20 opera volumio[1386]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 15 06:58:20 opera volumio[1386]: info: Raat Daemon started successfully May 15 06:58:20 opera volumio[1386]: info: Executing endpoint restartRAATSocket May 15 06:58:20 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection May 15 06:58:20 opera volumio[1386]: info: CorePlayQueue::getTrack 0 May 15 06:58:24 opera volumio[1386]: info: VolumeController::SetAlsaVolume85 May 15 06:58:24 opera volumio[1386]: info: CoreStateMachine::pushState May 15 06:58:24 opera volumio[1386]: info: CorePlayQueue::getTrack 0 May 15 06:58:24 opera volumio[1386]: info: CoreCommandRouter::volumioPushState May 15 06:58:24 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 06:58:24 opera volumio[1386]: info: Updating RAAT Signal Path May 15 06:58:25 opera volumio[1386]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 06:58:25 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 15 06:58:25 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 15 06:58:25 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 15 06:58:26 opera volumio[1386]: info: Received Get System Version May 15 06:58:26 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 06:58:32 opera volumio[1386]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 06:58:32 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 15 06:58:32 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 15 06:58:32 opera sudo[2692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 15 06:58:32 opera sudo[2692]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 06:58:32 opera sudo[2692]: pam_unix(sudo:session): session closed for user root May 15 06:58:32 opera sudo[2698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 15 06:58:32 opera sudo[2707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 15 06:58:32 opera sudo[2698]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 06:58:32 opera sudo[2707]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 06:58:32 opera sudo[2698]: pam_unix(sudo:session): session closed for user root May 15 06:58:32 opera sudo[2707]: pam_unix(sudo:session): session closed for user root May 15 06:58:32 opera sudo[2722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 15 06:58:32 opera sudo[2722]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 06:58:32 opera sudo[2730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 15 06:58:32 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 15 06:58:32 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 15 06:58:32 opera sudo[2730]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 06:58:32 opera sudo[2739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 15 06:58:32 opera sudo[2722]: pam_unix(sudo:session): session closed for user root May 15 06:58:32 opera sudo[2730]: pam_unix(sudo:session): session closed for user root May 15 06:58:32 opera sudo[2739]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 06:58:32 opera sudo[2739]: pam_unix(sudo:session): session closed for user root May 15 06:58:32 opera sudo[2755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 15 06:58:32 opera sudo[2755]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 06:58:33 opera sudo[2755]: pam_unix(sudo:session): session closed for user root May 15 06:58:56 opera volumio[1386]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/*/54f1757e-c7f7-45b7-bb22-6fb299bf5c98.jpg' May 15 06:59:11 opera dhcpd[1414]: DHCPDISCOVER from 48:e7:da:4e:fa:c3 (opera) via wlan0 May 15 06:59:12 opera dhcpd[1414]: DHCPOFFER on 192.168.211.6 to 48:e7:da:4e:fa:c3 (opera) via wlan0 May 15 06:59:14 opera volumio[1386]: info: VolumeController::SetAlsaVolume95 May 15 06:59:14 opera volumio[1386]: info: CoreStateMachine::pushState May 15 06:59:14 opera volumio[1386]: info: CorePlayQueue::getTrack 0 May 15 06:59:14 opera volumio[1386]: info: CoreCommandRouter::volumioPushState May 15 06:59:14 opera volumio[1386]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 06:59:14 opera volumio[1386]: info: Updating RAAT Signal Path May 15 06:59:22 opera volumio[1386]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 06:59:22 opera volumio[1386]: { Error: connect ETIMEDOUT 38.121.72.166:80 May 15 06:59:22 opera volumio[1386]: at Object._errnoException (util.js:1022:11) May 15 06:59:22 opera volumio[1386]: at _exceptionWithHostPort (util.js:1044:20) May 15 06:59:22 opera volumio[1386]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) May 15 06:59:22 opera volumio[1386]: code: 'ETIMEDOUT', May 15 06:59:22 opera volumio[1386]: errno: 'ETIMEDOUT', May 15 06:59:22 opera volumio[1386]: syscall: 'connect', May 15 06:59:22 opera volumio[1386]: address: '38.121.72.166', May 15 06:59:22 opera volumio[1386]: port: 80 } May 15 06:59:22 opera volumio[1386]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 06:59:22 opera sudo[2882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-15 06:58 May 15 06:59:22 opera sudo[2882]: 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"