-- Logs begin at Sun 2025-03-09 07:30:00 KST, end at Sun 2025-03-09 08:58:12 KST. -- Mar 09 08:57:00 volumio kernel: usb 1-1-port2: over-current change #183676 Mar 09 08:57:00 volumio kernel: usb 1-1-port2: over-current change #183677 Mar 09 08:57:00 volumio volumio[14478]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 08:57:00 volumio kernel: usb 1-1-port2: over-current change #183678 Mar 09 08:57:00 volumio kernel: usb 1-1-port2: over-current change #183679 Mar 09 08:57:01 volumio kernel: usb 1-1-port2: over-current change #183680 Mar 09 08:57:01 volumio kernel: usb 1-1-port2: over-current change #183681 Mar 09 08:57:01 volumio kernel: usb 1-1-port2: over-current change #183682 Mar 09 08:57:01 volumio kernel: usb 1-1-port2: over-current change #183683 Mar 09 08:57:02 volumio kernel: usb 1-1-port2: over-current change #183684 Mar 09 08:57:02 volumio kernel: usb 1-1-port2: over-current change #183685 Mar 09 08:57:02 volumio kernel: usb 1-1-port2: over-current change #183686 Mar 09 08:57:02 volumio kernel: usb 1-1-port2: over-current change #183687 Mar 09 08:57:03 volumio kernel: usb 1-1-port2: over-current change #183688 Mar 09 08:57:03 volumio kernel: usb 1-1-port2: over-current change #183689 Mar 09 08:57:03 volumio kernel: usb 1-1-port2: over-current change #183690 Mar 09 08:57:03 volumio kernel: usb 1-1-port2: over-current change #183691 Mar 09 08:57:04 volumio kernel: usb 1-1-port2: over-current change #183692 Mar 09 08:57:04 volumio kernel: usb 1-1-port2: over-current change #183693 Mar 09 08:57:04 volumio volumio[14478]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 08:57:04 volumio kernel: usb 1-1-port2: over-current change #183694 Mar 09 08:57:04 volumio kernel: usb 1-1-port2: over-current change #183695 Mar 09 08:57:05 volumio kernel: usb 1-1-port2: over-current change #183696 Mar 09 08:57:05 volumio kernel: usb 1-1-port2: over-current change #183697 Mar 09 08:57:05 volumio kernel: usb 1-1-port2: over-current change #183698 Mar 09 08:57:05 volumio kernel: usb 1-1-port2: over-current change #183699 Mar 09 08:57:05 volumio volumio[14478]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Mar 09 08:57:06 volumio kernel: usb 1-1-port2: over-current change #183700 Mar 09 08:57:06 volumio kernel: usb 1-1-port2: over-current change #183701 Mar 09 08:57:06 volumio kernel: usb 1-1-port2: over-current change #183702 Mar 09 08:57:06 volumio kernel: usb 1-1-port2: over-current change #183703 Mar 09 08:57:07 volumio volumio[14478]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 09 08:57:07 volumio kernel: usb 1-1-port2: over-current change #183704 Mar 09 08:57:07 volumio kernel: usb 1-1-port2: over-current change #183705 Mar 09 08:57:07 volumio kernel: usb 1-1-port2: over-current change #183706 Mar 09 08:57:07 volumio kernel: usb 1-1-port2: over-current change #183707 Mar 09 08:57:08 volumio kernel: usb 1-1-port2: over-current change #183708 Mar 09 08:57:08 volumio kernel: usb 1-1-port2: over-current change #183709 Mar 09 08:57:08 volumio volumio[14478]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 08:57:08 volumio kernel: usb 1-1-port2: over-current change #183710 Mar 09 08:57:09 volumio kernel: usb 1-1-port2: over-current change #183711 Mar 09 08:57:09 volumio kernel: usb 1-1-port2: over-current change #183712 Mar 09 08:57:09 volumio volumio[14478]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Mar 09 08:57:09 volumio volumio[14478]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Mar 09 08:57:09 volumio kernel: usb 1-1-port2: over-current change #183713 Mar 09 08:57:09 volumio volumio[14478]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 08:57:09 volumio volumio[14478]: Error: getaddrinfo -3007 Mar 09 08:57:09 volumio volumio[14478]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Mar 09 08:57:09 volumio volumio[14478]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Mar 09 08:57:09 volumio volumio[14478]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Mar 09 08:57:09 volumio volumio[14478]: code: -3007, Mar 09 08:57:09 volumio volumio[14478]: errno: -3007, Mar 09 08:57:09 volumio volumio[14478]: syscall: 'getaddrinfo' Mar 09 08:57:09 volumio volumio[14478]: } Mar 09 08:57:09 volumio volumio[14478]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 08:57:09 volumio kernel: usb 1-1-port2: over-current change #183714 Mar 09 08:57:10 volumio kernel: usb 1-1-port2: over-current change #183715 Mar 09 08:57:10 volumio sudo[15057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-09 08:56 Mar 09 08:57:10 volumio sudo[15057]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:57:10 volumio sudo[15057]: pam_unix(sudo:session): session closed for user root Mar 09 08:57:10 volumio volumio-remote-updater[558]: [2025-03-09 08:57:10] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Mar 09 08:57:10 volumio volumio-remote-updater[558]: [2025-03-09 08:57:10] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Mar 09 08:57:10 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Mar 09 08:57:10 volumio sudo[14986]: pam_unix(sudo:session): session closed for user root Mar 09 08:57:10 volumio kernel: usb 1-1-port2: over-current change #183716 Mar 09 08:57:10 volumio kernel: usb 1-1-port2: over-current change #183717 Mar 09 08:57:11 volumio kernel: usb 1-1-port2: over-current change #183718 Mar 09 08:57:11 volumio kernel: usb 1-1-port2: over-current change #183719 Mar 09 08:57:11 volumio kernel: usb 1-1-port2: over-current change #183720 Mar 09 08:57:11 volumio kernel: usb 1-1-port2: over-current change #183721 Mar 09 08:57:12 volumio kernel: usb 1-1-port2: over-current change #183722 Mar 09 08:57:12 volumio kernel: usb 1-1-port2: over-current change #183723 Mar 09 08:57:12 volumio kernel: usb 1-1-port2: over-current change #183724 Mar 09 08:57:12 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Mar 09 08:57:12 volumio systemd[1]: Started dynamicswap service. Mar 09 08:57:12 volumio systemd[1]: dynamicswap.service: Succeeded. Mar 09 08:57:12 volumio kernel: usb 1-1-port2: over-current change #183725 Mar 09 08:57:13 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Mar 09 08:57:13 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8. Mar 09 08:57:13 volumio systemd[1]: Started dynamicswap service. Mar 09 08:57:13 volumio systemd[1]: Stopped Volumio Backend Module. Mar 09 08:57:13 volumio systemd[1]: Started Volumio Backend Module. Mar 09 08:57:13 volumio systemd[1]: dynamicswap.service: Succeeded. Mar 09 08:57:13 volumio kernel: usb 1-1-port2: over-current change #183726 Mar 09 08:57:13 volumio kernel: usb 1-1-port2: over-current change #183727 Mar 09 08:57:13 volumio kernel: usb 1-1-port2: over-current change #183728 Mar 09 08:57:13 volumio kernel: usb 1-1-port2: over-current change #183729 Mar 09 08:57:14 volumio kernel: usb 1-1-port2: over-current change #183730 Mar 09 08:57:14 volumio kernel: usb 1-1-port2: over-current change #183731 Mar 09 08:57:14 volumio kernel: usb 1-1-port2: over-current change #183732 Mar 09 08:57:14 volumio volumio[15074]: info: ------------------------------------------- Mar 09 08:57:14 volumio volumio[15074]: info: ----- Volumio3 ---- Mar 09 08:57:14 volumio volumio[15074]: info: ------------------------------------------- Mar 09 08:57:14 volumio volumio[15074]: info: ----- System startup ---- Mar 09 08:57:14 volumio volumio[15074]: info: ------------------------------------------- Mar 09 08:57:14 volumio kernel: usb 1-1-port2: over-current change #183733 Mar 09 08:57:15 volumio kernel: usb 1-1-port2: over-current change #183734 Mar 09 08:57:15 volumio volumio-remote-updater[558]: [2025-03-09 08:57:15] [connect] Successful connection Mar 09 08:57:15 volumio kernel: usb 1-1-port2: over-current change #183735 Mar 09 08:57:15 volumio volumio[15074]: info: MYVOLUMIO Environment detected Mar 09 08:57:15 volumio volumio[15074]: info: Plugin folders cleanup Mar 09 08:57:15 volumio volumio[15074]: info: Scanning into folder /volumio/app/plugins/ Mar 09 08:57:15 volumio volumio[15074]: info: Scanning category audio_interface Mar 09 08:57:15 volumio volumio[15074]: info: Scanning category miscellanea Mar 09 08:57:15 volumio volumio[15074]: info: Scanning category music_service Mar 09 08:57:15 volumio volumio[15074]: info: Scanning category plugins.json Mar 09 08:57:15 volumio volumio[15074]: info: Scanning category system_controller Mar 09 08:57:15 volumio volumio[15074]: info: Scanning category user_interface Mar 09 08:57:15 volumio volumio[15074]: info: Scanning into folder /data/plugins/ Mar 09 08:57:15 volumio volumio[15074]: info: Scanning category music_service Mar 09 08:57:15 volumio volumio[15074]: info: Scanning category system_controller Mar 09 08:57:15 volumio volumio[15074]: info: Scanning category user_interface Mar 09 08:57:15 volumio volumio[15074]: info: Plugin folders cleanup completed Mar 09 08:57:15 volumio volumio[15074]: info: ------------------------------------------- Mar 09 08:57:15 volumio volumio[15074]: info: ----- Core plugins startup ---- Mar 09 08:57:15 volumio volumio[15074]: info: ------------------------------------------- Mar 09 08:57:15 volumio volumio[15074]: info: Loading plugins from folder /volumio/app/plugins/ Mar 09 08:57:15 volumio volumio[15074]: info: Adding plugin upnp to MyMusic Plugins Mar 09 08:57:15 volumio volumio[15074]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 09 08:57:15 volumio volumio[15074]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 09 08:57:15 volumio volumio[15074]: info: Loading plugins from folder /data/plugins/ Mar 09 08:57:15 volumio kernel: usb 1-1-port2: over-current change #183736 Mar 09 08:57:15 volumio volumio[15074]: info: Loading plugin "system"... Mar 09 08:57:15 volumio volumio[15074]: info: Loading plugin "appearance"... Mar 09 08:57:15 volumio kernel: usb 1-1-port2: over-current change #183737 Mar 09 08:57:16 volumio kernel: usb 1-1-port2: over-current change #183738 Mar 09 08:57:16 volumio kernel: usb 1-1-port2: over-current change #183739 Mar 09 08:57:16 volumio kernel: usb 1-1-port2: over-current change #183740 Mar 09 08:57:16 volumio volumio[15074]: info: Loading plugin "network"... Mar 09 08:57:16 volumio volumio[15074]: info: Refreshing Cached IP Addresses Mar 09 08:57:16 volumio sudo[15101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 09 08:57:16 volumio sudo[15101]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:57:16 volumio sudo[15101]: pam_unix(sudo:session): session closed for user root Mar 09 08:57:16 volumio sudo[15103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 09 08:57:16 volumio sudo[15103]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:57:16 volumio sudo[15103]: pam_unix(sudo:session): session closed for user root Mar 09 08:57:16 volumio volumio[15074]: info: Loading plugin "services"... Mar 09 08:57:16 volumio volumio[15074]: info: Loading plugin "alsa_controller"... Mar 09 08:57:16 volumio sudo[15112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 09 08:57:16 volumio sudo[15112]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:57:16 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 09 08:57:16 volumio volumio[15074]: info: Loading plugin "wizard"... Mar 09 08:57:16 volumio volumio[15074]: info: Loading plugin "networkfs"... Mar 09 08:57:16 volumio volumio[15074]: info: Starting Udev Watcher for removable devices Mar 09 08:57:16 volumio volumio[15074]: info: Ignoring mount for partition: boot Mar 09 08:57:16 volumio volumio[15074]: info: Ignoring mount for partition: volumio Mar 09 08:57:16 volumio volumio[15074]: info: Ignoring mount for partition: volumio_data Mar 09 08:57:16 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 09 08:57:16 volumio volumio[15074]: info: Loading plugin "volumio_command_line_client"... Mar 09 08:57:16 volumio volumio[15074]: info: Loading plugin "upnp"... Mar 09 08:57:16 volumio volumio[15074]: info: [1741478236981] Starting Upmpd Daemon Mar 09 08:57:16 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 09 08:57:16 volumio volumio[15074]: info: Loading plugin "my_music"... Mar 09 08:57:16 volumio volumio[15074]: info: Loading plugin "mpd"... Mar 09 08:57:16 volumio kernel: usb 1-1-port2: over-current change #183741 Mar 09 08:57:17 volumio kernel: usb 1-1-port2: over-current change #183742 Mar 09 08:57:17 volumio volumio[15074]: info: Loading plugin "upnp_browser"... Mar 09 08:57:17 volumio kernel: usb 1-1-port2: over-current change #183743 Mar 09 08:57:17 volumio sudo[15112]: pam_unix(sudo:session): session closed for user root Mar 09 08:57:17 volumio volumio[15074]: info: Loading plugin "alarm-clock"... Mar 09 08:57:17 volumio kernel: usb 1-1-port2: over-current change #183744 Mar 09 08:57:17 volumio volumio[15074]: info: Loading plugin "airplay_emulation"... Mar 09 08:57:17 volumio volumio[15074]: info: Starting Shairport Sync Mar 09 08:57:17 volumio volumio[15074]: info: Loading plugin "last_100"... Mar 09 08:57:17 volumio volumio[15074]: info: Loading plugin "webradio"... Mar 09 08:57:17 volumio volumio[15074]: info: Loading plugin "i2s_dacs"... Mar 09 08:57:17 volumio volumio[15074]: info: Loading plugin "volumiodiscovery"... Mar 09 08:57:17 volumio volumio[15074]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 09 08:57:17 volumio volumio[15074]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 09 08:57:17 volumio volumio[15074]: *** WARNING *** For more information see Mar 09 08:57:17 volumio node[15074]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 09 08:57:17 volumio volumio[15074]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 09 08:57:17 volumio volumio[15074]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 09 08:57:17 volumio volumio[15074]: *** WARNING *** For more information see Mar 09 08:57:17 volumio node[15074]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 09 08:57:17 volumio node[15074]: *** WARNING *** For more information see Mar 09 08:57:17 volumio node[15074]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 09 08:57:17 volumio node[15074]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 09 08:57:17 volumio node[15074]: *** WARNING *** For more information see Mar 09 08:57:17 volumio volumio[15074]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 09 08:57:17 volumio volumio[15074]: info: Discovery: Started advertising with name: Volumio Mar 09 08:57:18 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 09 08:57:18 volumio volumio[15074]: info: Loading plugin "now_playing"... Mar 09 08:57:18 volumio kernel: usb 1-1-port2: over-current change #183745 Mar 09 08:57:18 volumio kernel: usb 1-1-port2: over-current change #183746 Mar 09 08:57:18 volumio kernel: usb 1-1-port2: over-current change #183747 Mar 09 08:57:18 volumio kernel: usb 1-1-port2: over-current change #183748 Mar 09 08:57:19 volumio kernel: usb 1-1-port2: over-current change #183749 Mar 09 08:57:19 volumio kernel: usb 1-1-port2: over-current change #183750 Mar 09 08:57:19 volumio volumio[15074]: info: Loading plugin "outputs"... Mar 09 08:57:19 volumio volumio[15074]: info: Loading plugin "albumart"... Mar 09 08:57:19 volumio volumio[15074]: info: Plugin example_plugin is not enabled Mar 09 08:57:19 volumio volumio[15074]: info: Loading plugin "inputs"... Mar 09 08:57:19 volumio volumio[15074]: info: Loading plugin "updater_comm"... Mar 09 08:57:19 volumio kernel: usb 1-1-port2: over-current change #183751 Mar 09 08:57:19 volumio volumio[15074]: info: Plugin mpdemulation is not enabled Mar 09 08:57:19 volumio volumio[15074]: info: Loading plugin "rest_api"... Mar 09 08:57:19 volumio volumio[15074]: info: Loading plugin "websocket"... Mar 09 08:57:19 volumio volumio[15074]: info: Starting Socket.io Server version 2.3.0 Mar 09 08:57:19 volumio volumio[15074]: info: Loading plugin "personal_radio"... Mar 09 08:57:19 volumio kernel: usb 1-1-port2: over-current change #183752 Mar 09 08:57:19 volumio volumio[15074]: Forking 3 albumart workers Mar 09 08:57:20 volumio kernel: usb 1-1-port2: over-current change #183753 Mar 09 08:57:20 volumio kernel: usb 1-1-port2: over-current change #183754 Mar 09 08:57:20 volumio volumio[15074]: info: ControllerPersonalRadio::constructor Mar 09 08:57:20 volumio volumio[15074]: info: Loading plugin "podcast"... Mar 09 08:57:20 volumio kernel: usb 1-1-port2: over-current change #183755 Mar 09 08:57:20 volumio volumio[15074]: info: ControllerPodcast::constructor Mar 09 08:57:20 volumio kernel: usb 1-1-port2: over-current change #183756 Mar 09 08:57:20 volumio volumio[15074]: info: Loading plugin "radio_paradise"... Mar 09 08:57:21 volumio volumio[15074]: Starting albumart workers Mar 09 08:57:21 volumio volumio[15074]: Starting albumart workers Mar 09 08:57:21 volumio kernel: usb 1-1-port2: over-current change #183757 Mar 09 08:57:21 volumio volumio[15074]: Starting albumart workers Mar 09 08:57:21 volumio volumio[15074]: info: Applying required configuration parameters for plugin radio_paradise Mar 09 08:57:21 volumio volumio[15074]: info: [1741478241296] [RadioParadise] API delay: 5 Mar 09 08:57:21 volumio volumio[15074]: info: Loading plugin "backup_restore"... Mar 09 08:57:21 volumio kernel: usb 1-1-port2: over-current change #183758 Mar 09 08:57:21 volumio volumio[15074]: info: Applying required configuration parameters for plugin backup_restore Mar 09 08:57:21 volumio volumio[15074]: info: Loading plugin "Systeminfo"... Mar 09 08:57:21 volumio kernel: usb 1-1-port2: over-current change #183759 Mar 09 08:57:21 volumio kernel: usb 1-1-port2: over-current change #183760 Mar 09 08:57:22 volumio volumio[15074]: info: Loading plugin "lastfm"... Mar 09 08:57:22 volumio kernel: usb 1-1-port2: over-current change #183761 Mar 09 08:57:22 volumio kernel: usb 1-1-port2: over-current change #183762 Mar 09 08:57:22 volumio volumio[15074]: info: Loading plugin "randomizer"... Mar 09 08:57:22 volumio kernel: usb 1-1-port2: over-current change #183763 Mar 09 08:57:22 volumio volumio[15074]: info: Applying required configuration parameters for plugin randomizer Mar 09 08:57:22 volumio kernel: usb 1-1-port2: over-current change #183764 Mar 09 08:57:22 volumio volumio[15074]: info: Loading i18n strings for locale ko Mar 09 08:57:22 volumio volumio[15074]: Updating browse sources language Mar 09 08:57:22 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::initPlayerControls Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 08:57:23 volumio volumio[15074]: Express server listening on port 3000 Mar 09 08:57:23 volumio volumio[15074]: [Metrics] WebUI: 8s 800.00ms Mar 09 08:57:23 volumio volumio[15074]: info: CoreStateMachine::resetVolumioState Mar 09 08:57:23 volumio volumio[15074]: info: CoreStateMachine::getcurrentVolume Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::volumioRetrievevolume Mar 09 08:57:23 volumio volumio[15074]: info: CoreStateMachine::pushState Mar 09 08:57:23 volumio volumio[15074]: info: CorePlayQueue::getTrack 0 Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::volumioPushState Mar 09 08:57:23 volumio kernel: usb 1-1-port2: over-current change #183765 Mar 09 08:57:23 volumio volumio[15074]: info: Volumio Network Manager: Network status updated: 1 Mar 09 08:57:23 volumio volumio[15074]: verbose: New Socket.io Connection to 192.168.219.100 from 192.168.219.104 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Mar 09 08:57:23 volumio volumio[15074]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:57:23 volumio volumio[15074]: info: Reloading queue from file Mar 09 08:57:23 volumio volumio[15074]: info: CoreStateMachine::setRepeat true single undefined Mar 09 08:57:23 volumio volumio[15074]: info: CoreStateMachine::pushState Mar 09 08:57:23 volumio volumio[15074]: info: CorePlayQueue::getTrack 0 Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::volumioPushState Mar 09 08:57:23 volumio volumio[15074]: info: CoreStateMachine::setRandom null Mar 09 08:57:23 volumio volumio[15074]: info: CoreStateMachine::pushState Mar 09 08:57:23 volumio volumio[15074]: info: CorePlayQueue::getTrack 0 Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::volumioPushState Mar 09 08:57:23 volumio volumio[15074]: info: Setting Device type: Raspberry PI Mar 09 08:57:23 volumio volumio[15074]: info: Completed loading Core Plugins Mar 09 08:57:23 volumio volumio[15074]: info: Preparing to generate the ALSA configuration file Mar 09 08:57:23 volumio kernel: usb 1-1-port2: over-current change #183766 Mar 09 08:57:23 volumio volumio[15074]: info: Discovery: adding 9f609d20-c190-4d3b-84ba-3e254004455a Mar 09 08:57:23 volumio volumio[15074]: info: Discovery: Found device Volumio Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::volumioGetState Mar 09 08:57:23 volumio volumio[15074]: info: CorePlayQueue::getTrack 0 Mar 09 08:57:23 volumio volumio[15074]: info: Asound.conf file unchanged, so no further update is needed Mar 09 08:57:23 volumio volumio[15074]: info: Output device has changed, restarting MPD Mar 09 08:57:23 volumio sudo[15189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 09 08:57:23 volumio sudo[15189]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:57:23 volumio volumio[15074]: info: Output device has changed, restarting Shairport Sync Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:23 volumio sudo[15189]: pam_unix(sudo:session): session closed for user root Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:57:23 volumio sudo[15192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 09 08:57:23 volumio sudo[15192]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:57:23 volumio systemd[1]: Stopping Music Player Daemon... Mar 09 08:57:23 volumio volumio[15074]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 09 08:57:23 volumio volumio[15074]: info: ___________ START PLUGINS ___________ Mar 09 08:57:23 volumio volumio[15074]: info: ControllerMpd::onStart: Initializing MPD Mar 09 08:57:23 volumio volumio[15074]: info: Creating MPD Configuration file Mar 09 08:57:23 volumio sudo[15198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 09 08:57:23 volumio sudo[15198]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:57:23 volumio systemd[1]: mpd.service: Succeeded. Mar 09 08:57:23 volumio systemd[1]: Stopped Music Player Daemon. Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 09 08:57:23 volumio kernel: usb 1-1-port2: over-current change #183767 Mar 09 08:57:23 volumio volumio[15074]: info: [1741478243681] CoreMusicLibrary::Adding element 미디어 서버 Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 08:57:23 volumio sudo[15198]: pam_unix(sudo:session): session closed for user root Mar 09 08:57:23 volumio sudo[15201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:57:23 volumio systemd[1]: Starting Music Player Daemon... Mar 09 08:57:23 volumio sudo[15201]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:57:23 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Mar 09 08:57:23 volumio systemd[1]: mpd.service: Succeeded. Mar 09 08:57:23 volumio systemd[1]: Stopped Music Player Daemon. Mar 09 08:57:23 volumio volumio[15074]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:57:23 volumio systemd[1]: Starting Music Player Daemon... Mar 09 08:57:23 volumio volumio[15074]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 09 08:57:23 volumio volumio[15074]: info: [1741478243826] CoreMusicLibrary::Adding element Last_100 Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 09 08:57:23 volumio volumio[15074]: info: [1741478243830] CoreMusicLibrary::Adding element Webradio Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 09 08:57:23 volumio volumio[15074]: info: Initializing BBC Radios Mar 09 08:57:23 volumio sudo[15207]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 09 08:57:23 volumio sudo[15207]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:57:23 volumio sudo[15207]: pam_unix(sudo:session): session closed for user root Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 09 08:57:23 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:57:23 volumio kernel: usb 1-1-port2: over-current change #183768 Mar 09 08:57:23 volumio volumio[15074]: info: [now-playing] ConfigUpdater: config is up to date. Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 09 08:57:24 volumio volumio[15074]: info: [1741478244027] CoreMusicLibrary::Adding element 개인 라디오 Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 08:57:24 volumio volumio[15074]: Cannot find translation for source 개인 라디오 Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 09 08:57:24 volumio volumio[15074]: info: [1741478244037] CoreMusicLibrary::Adding element 팟캐스트 Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 08:57:24 volumio volumio[15074]: Cannot find translation for source 개인 라디오 Mar 09 08:57:24 volumio volumio[15074]: Cannot find translation for source 팟캐스트 Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 09 08:57:24 volumio volumio[15074]: info: [1741478244044] CoreMusicLibrary::Adding element Radio Paradise Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 08:57:24 volumio volumio[15074]: Cannot find translation for source 개인 라디오 Mar 09 08:57:24 volumio volumio[15074]: Cannot find translation for source 팟캐스트 Mar 09 08:57:24 volumio volumio[15074]: Cannot find translation for source Radio Paradise Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 09 08:57:24 volumio volumio[15074]: info: [1741478244049] CoreMusicLibrary::Adding element LastFM Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 08:57:24 volumio volumio[15074]: Cannot find translation for source 개인 라디오 Mar 09 08:57:24 volumio volumio[15074]: Cannot find translation for source 팟캐스트 Mar 09 08:57:24 volumio volumio[15074]: Cannot find translation for source Radio Paradise Mar 09 08:57:24 volumio volumio[15074]: Cannot find translation for source LastFM Mar 09 08:57:24 volumio volumio[15074]: info: [LastFM] scrobbler initiated! Mar 09 08:57:24 volumio volumio[15074]: info: [LastFM] extended logging: false Mar 09 08:57:24 volumio volumio[15074]: info: [LastFM] try scrobble stream/radio plays: true Mar 09 08:57:24 volumio volumio[15074]: info: [LastFM] Left init routine Mar 09 08:57:24 volumio volumio[15074]: info: [LastFM] Socket already connected: true Mar 09 08:57:24 volumio volumio[15074]: info: Volumio Calling Home Mar 09 08:57:24 volumio kernel: usb 1-1-port2: over-current change #183769 Mar 09 08:57:24 volumio volumio[15074]: info: [now-playing] App is listening on port 4004. Mar 09 08:57:24 volumio volumio[15074]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Mar 09 08:57:24 volumio volumio[15074]: verbose: New Socket.io Connection to 192.168.219.100 from 192.168.219.104 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Mar 09 08:57:24 volumio volumio[15074]: info: MPD Permissions set Mar 09 08:57:24 volumio volumio[15074]: info: MPD Permissions set Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 08:57:24 volumio kernel: usb 1-1-port2: over-current change #183770 Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::volumioGetVisibleSources Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::volumioGetState Mar 09 08:57:24 volumio volumio[15074]: info: CorePlayQueue::getTrack 0 Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::volumioGetState Mar 09 08:57:24 volumio volumio[15074]: info: CorePlayQueue::getTrack 0 Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::volumioGetQueue Mar 09 08:57:24 volumio volumio[15074]: info: CoreStateMachine::getQueue Mar 09 08:57:24 volumio volumio[15074]: info: CorePlayQueue::getQueue Mar 09 08:57:24 volumio volumio[15074]: info: Listing playlists Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 09 08:57:24 volumio volumio[15074]: info: Received Get System Info Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 09 08:57:24 volumio volumio[15074]: info: Discovery: Getting this device information Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::volumioGetState Mar 09 08:57:24 volumio volumio[15074]: info: CorePlayQueue::getTrack 0 Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::volumioGetState Mar 09 08:57:24 volumio volumio[15074]: info: CorePlayQueue::getTrack 0 Mar 09 08:57:24 volumio volumio[15074]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 09 08:57:24 volumio volumio[15074]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 08:57:24 volumio kernel: usb 1-1-port2: over-current change #183771 Mar 09 08:57:24 volumio volumio[15074]: info: Volumio called home Mar 09 08:57:24 volumio volumio[15074]: info: Starting Shairport Sync Mar 09 08:57:24 volumio volumio[15074]: info: Starting Shairport Sync Mar 09 08:57:24 volumio sudo[15237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 09 08:57:24 volumio volumio[15074]: info: Starting Shairport Sync Mar 09 08:57:24 volumio sudo[15237]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:57:24 volumio sudo[15239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 09 08:57:24 volumio sudo[15239]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:57:24 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 09 08:57:24 volumio sudo[15242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 09 08:57:24 volumio sudo[15242]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:57:24 volumio kernel: usb 1-1-port2: over-current change #183772 Mar 09 08:57:24 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 09 08:57:24 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 09 08:57:24 volumio volumio[15074]: (node:15074) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Mar 09 08:57:24 volumio volumio[15074]: (Use `node --trace-deprecation ...` to show where the warning was created) Mar 09 08:57:24 volumio volumio[15074]: (node:15074) [DEP0106] DeprecationWarning: crypto.createDecipher is deprecated. Mar 09 08:57:24 volumio volumio[15074]: info: CoreCommandRouter::volumioGetState Mar 09 08:57:24 volumio volumio[15074]: info: CorePlayQueue::getTrack 0 Mar 09 08:57:25 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 09 08:57:25 volumio sudo[15237]: pam_unix(sudo:session): session closed for user root Mar 09 08:57:25 volumio sudo[15239]: pam_unix(sudo:session): session closed for user root Mar 09 08:57:25 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 09 08:57:25 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 09 08:57:25 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 09 08:57:25 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 09 08:57:25 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 09 08:57:25 volumio sudo[15242]: pam_unix(sudo:session): session closed for user root Mar 09 08:57:25 volumio volumio[15074]: info: Shairport-Sync Started Mar 09 08:57:25 volumio volumio[15074]: Error adding Membership: Error: addMembership EINVAL Mar 09 08:57:25 volumio volumio[15074]: info: Shairport-Sync Started Mar 09 08:57:25 volumio volumio[15074]: info: Shairport-Sync Started Mar 09 08:57:25 volumio volumio[15074]: info: [LastFM] finished init: Authenticated successfully! Mar 09 08:57:25 volumio kernel: usb 1-1-port2: over-current change #183773 Mar 09 08:57:25 volumio kernel: usb 1-1-port2: over-current change #183774 Mar 09 08:57:25 volumio volumio[15074]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 09 08:57:25 volumio mpd[15223]: Mar 09 08:57 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 09 08:57:25 volumio systemd[1]: Started Music Player Daemon. Mar 09 08:57:25 volumio sudo[15201]: pam_unix(sudo:session): session closed for user root Mar 09 08:57:25 volumio sudo[15192]: pam_unix(sudo:session): session closed for user root Mar 09 08:57:25 volumio volumio[15074]: info: Completed starting Core Plugins Mar 09 08:57:25 volumio volumio[15074]: info: ------------------------------------------- Mar 09 08:57:25 volumio volumio[15074]: info: ----- MyVolumio plugins startup ---- Mar 09 08:57:25 volumio volumio[15074]: info: ------------------------------------------- Mar 09 08:57:25 volumio volumio[15074]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 09 08:57:25 volumio kernel: usb 1-1-port2: over-current change #183775 Mar 09 08:57:25 volumio volumio[15074]: error: MPD error: The expression evaluated to a falsy value: Mar 09 08:57:25 volumio volumio[15074]: assert.ok(self.idling) Mar 09 08:57:25 volumio volumio[15074]: error: The expression evaluated to a falsy value: Mar 09 08:57:25 volumio volumio[15074]: assert.ok(self.idling) Mar 09 08:57:25 volumio volumio[15074]: error: updateQueue error: null Mar 09 08:57:25 volumio volumio[15074]: info: MPD running with PID15223 Mar 09 08:57:25 volumio volumio[15074]: ,establishing connection Mar 09 08:57:25 volumio volumio[15074]: error: updateQueue error: null Mar 09 08:57:25 volumio kernel: usb 1-1-port2: over-current change #183776 Mar 09 08:57:26 volumio kernel: usb 1-1-port2: over-current change #183777 Mar 09 08:57:26 volumio kernel: usb 1-1-port2: over-current change #183778 Mar 09 08:57:26 volumio kernel: usb 1-1-port2: over-current change #183779 Mar 09 08:57:26 volumio volumio[15074]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 09 08:57:27 volumio sudo[15269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 09 08:57:27 volumio kernel: usb 1-1-port2: over-current change #183780 Mar 09 08:57:27 volumio sudo[15269]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:57:27 volumio sudo[15269]: pam_unix(sudo:session): session closed for user root Mar 09 08:57:27 volumio sudo[15271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 09 08:57:27 volumio sudo[15271]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:57:27 volumio sudo[15271]: pam_unix(sudo:session): session closed for user root Mar 09 08:57:27 volumio sudo[15275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 09 08:57:27 volumio sudo[15275]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:57:27 volumio sudo[15275]: pam_unix(sudo:session): session closed for user root Mar 09 08:57:27 volumio volumio[15074]: info: Upmpdcli Daemon Started Mar 09 08:57:27 volumio kernel: usb 1-1-port2: over-current change #183781 Mar 09 08:57:27 volumio kernel: usb 1-1-port2: over-current change #183782 Mar 09 08:57:27 volumio kernel: usb 1-1-port2: over-current change #183783 Mar 09 08:57:27 volumio volumio-remote-updater[558]: [2025-03-09 08:57:27] [connect] Successful connection Mar 09 08:57:27 volumio volumio-remote-updater[558]: [2025-03-09 08:57:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1741478247 101 Mar 09 08:57:27 volumio volumio[15074]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 8 Mar 09 08:57:28 volumio kernel: usb 1-1-port2: over-current change #183784 Mar 09 08:57:28 volumio kernel: usb 1-1-port2: over-current change #183785 Mar 09 08:57:28 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 08:57:28 volumio kernel: usb 1-1-port2: over-current change #183786 Mar 09 08:57:28 volumio kernel: usb 1-1-port2: over-current change #183787 Mar 09 08:57:29 volumio kernel: usb 1-1-port2: over-current change #183788 Mar 09 08:57:29 volumio kernel: usb 1-1-port2: over-current change #183789 Mar 09 08:57:29 volumio kernel: usb 1-1-port2: over-current change #183790 Mar 09 08:57:29 volumio kernel: usb 1-1-port2: over-current change #183791 Mar 09 08:57:30 volumio kernel: usb 1-1-port2: over-current change #183792 Mar 09 08:57:30 volumio kernel: usb 1-1-port2: over-current change #183793 Mar 09 08:57:30 volumio kernel: usb 1-1-port2: over-current change #183794 Mar 09 08:57:30 volumio volumio[15074]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 09 08:57:30 volumio kernel: usb 1-1-port2: over-current change #183795 Mar 09 08:57:31 volumio kernel: usb 1-1-port2: over-current change #183796 Mar 09 08:57:31 volumio kernel: usb 1-1-port2: over-current change #183797 Mar 09 08:57:31 volumio kernel: usb 1-1-port2: over-current change #183798 Mar 09 08:57:31 volumio kernel: usb 1-1-port2: over-current change #183799 Mar 09 08:57:32 volumio kernel: usb 1-1-port2: over-current change #183800 Mar 09 08:57:32 volumio kernel: usb 1-1-port2: over-current change #183801 Mar 09 08:57:32 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 08:57:32 volumio kernel: usb 1-1-port2: over-current change #183802 Mar 09 08:57:32 volumio kernel: usb 1-1-port2: over-current change #183803 Mar 09 08:57:33 volumio kernel: usb 1-1-port2: over-current change #183804 Mar 09 08:57:33 volumio kernel: usb 1-1-port2: over-current change #183805 Mar 09 08:57:33 volumio kernel: usb 1-1-port2: over-current change #183806 Mar 09 08:57:33 volumio kernel: usb 1-1-port2: over-current change #183807 Mar 09 08:57:34 volumio kernel: usb 1-1-port2: over-current change #183808 Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 09 08:57:34 volumio volumio[15074]: info: Adding plugin bluetooth to MyMusic Plugins Mar 09 08:57:34 volumio volumio[15074]: info: Adding plugin multiroom to MyMusic Plugins Mar 09 08:57:34 volumio volumio[15074]: info: Adding plugin metavolumio to MyMusic Plugins Mar 09 08:57:34 volumio volumio[15074]: info: Adding plugin cd_controller to MyMusic Plugins Mar 09 08:57:34 volumio volumio[15074]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 09 08:57:34 volumio volumio[15074]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 09 08:57:34 volumio volumio[15074]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 09 08:57:34 volumio kernel: usb 1-1-port2: over-current change #183809 Mar 09 08:57:34 volumio kernel: usb 1-1-port2: over-current change #183810 Mar 09 08:57:34 volumio kernel: usb 1-1-port2: over-current change #183811 Mar 09 08:57:35 volumio kernel: usb 1-1-port2: over-current change #183812 Mar 09 08:57:35 volumio volumio[15074]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 09 08:57:35 volumio volumio[15074]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 09 08:57:35 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:57:35 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:57:35 volumio volumio[15074]: info: Starting MyVolumio Remote Streaming Endpoints Mar 09 08:57:35 volumio volumio[15074]: info: MyVolumio login type: Token Mar 09 08:57:35 volumio volumio[15074]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 09 08:57:35 volumio volumio[15074]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 09 08:57:35 volumio kernel: usb 1-1-port2: over-current change #183813 Mar 09 08:57:35 volumio kernel: usb 1-1-port2: over-current change #183814 Mar 09 08:57:36 volumio kernel: usb 1-1-port2: over-current change #183815 Mar 09 08:57:36 volumio kernel: usb 1-1-port2: over-current change #183816 Mar 09 08:57:36 volumio volumio[15074]: info: Starting Streaming Service Transparent Proxy Mar 09 08:57:36 volumio volumio[15074]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 09 08:57:36 volumio volumio[15074]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 09 08:57:36 volumio volumio[15074]: info: Streaming services startup Mar 09 08:57:36 volumio volumio[15074]: info: Starting Streaming Daemon Mar 09 08:57:36 volumio sudo[15288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 09 08:57:36 volumio sudo[15288]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:57:36 volumio kernel: usb 1-1-port2: over-current change #183817 Mar 09 08:57:36 volumio sudo[15288]: pam_unix(sudo:session): session closed for user root Mar 09 08:57:36 volumio volumio[15074]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 09 08:57:36 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 08:57:36 volumio volumio[15074]: error: Cannot start Volumio Streaming Daemon Mar 09 08:57:36 volumio volumio[15074]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 09 08:57:36 volumio volumio[15074]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 09 08:57:36 volumio kernel: usb 1-1-port2: over-current change #183818 Mar 09 08:57:37 volumio kernel: usb 1-1-port2: over-current change #183819 Mar 09 08:57:37 volumio volumio[15074]: error: MyVolumio Custom Token format not valid, refreshing it Mar 09 08:57:37 volumio kernel: usb 1-1-port2: over-current change #183820 Mar 09 08:57:37 volumio kernel: usb 1-1-port2: over-current change #183821 Mar 09 08:57:37 volumio volumio[15074]: STREAMING PROXY: Starting server on port 3245 Mar 09 08:57:37 volumio volumio[15074]: Node JS runtime: 14 Mar 09 08:57:37 volumio kernel: usb 1-1-port2: over-current change #183822 Mar 09 08:57:38 volumio kernel: usb 1-1-port2: over-current change #183823 Mar 09 08:57:38 volumio kernel: usb 1-1-port2: over-current change #183824 Mar 09 08:57:38 volumio volumio[15074]: info: MyVolumio login type: Token Mar 09 08:57:38 volumio kernel: usb 1-1-port2: over-current change #183825 Mar 09 08:57:38 volumio kernel: usb 1-1-port2: over-current change #183826 Mar 09 08:57:39 volumio kernel: usb 1-1-port2: over-current change #183827 Mar 09 08:57:39 volumio kernel: usb 1-1-port2: over-current change #183828 Mar 09 08:57:39 volumio volumio[15074]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 09 08:57:39 volumio kernel: usb 1-1-port2: over-current change #183829 Mar 09 08:57:39 volumio kernel: usb 1-1-port2: over-current change #183830 Mar 09 08:57:40 volumio volumio[15074]: info: MyVolumio token set successfully Mar 09 08:57:40 volumio volumio[15074]: info: MYVOLUMIO: Adding device Mar 09 08:57:40 volumio volumio[15074]: info: MYVOLUMIO: Evaluating Server Mar 09 08:57:40 volumio kernel: usb 1-1-port2: over-current change #183831 Mar 09 08:57:40 volumio kernel: usb 1-1-port2: over-current change #183832 Mar 09 08:57:40 volumio volumio[15074]: info: MyVolumio status changed Mar 09 08:57:40 volumio volumio[15074]: info: Streaming services startup Mar 09 08:57:40 volumio volumio[15074]: info: Starting Streaming Daemon Mar 09 08:57:40 volumio volumio[15074]: info: Removing browser output: myVolumio user plan is not superstar Mar 09 08:57:40 volumio volumio[15074]: info: Removing audio output: Mar 09 08:57:40 volumio volumio[15074]: info: Stoppping Tunnel 1 Mar 09 08:57:40 volumio sudo[15327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 09 08:57:40 volumio sudo[15327]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:57:40 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 08:57:40 volumio kernel: usb 1-1-port2: over-current change #183833 Mar 09 08:57:40 volumio sudo[15330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 09 08:57:40 volumio sudo[15327]: pam_unix(sudo:session): session closed for user root Mar 09 08:57:40 volumio sudo[15330]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 08:57:40 volumio volumio[15074]: error: Cannot start Volumio Streaming Daemon Mar 09 08:57:40 volumio volumio[15074]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 09 08:57:40 volumio volumio[15074]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 09 08:57:40 volumio sudo[15330]: pam_unix(sudo:session): session closed for user root Mar 09 08:57:40 volumio volumio[15074]: info: Remote SSH Stopped Mar 09 08:57:40 volumio volumio[15074]: info: Setting Geolocation for MyVolumio to as1 Mar 09 08:57:40 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:57:40 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:57:40 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:57:40 volumio kernel: usb 1-1-port2: over-current change #183834 Mar 09 08:57:41 volumio kernel: usb 1-1-port2: over-current change #183835 Mar 09 08:57:41 volumio kernel: usb 1-1-port2: over-current change #183836 Mar 09 08:57:41 volumio kernel: usb 1-1-port2: over-current change #183837 Mar 09 08:57:41 volumio volumio[15074]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 09 08:57:41 volumio volumio[15074]: info: Updating MyVolumio device info Mar 09 08:57:41 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:57:41 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:57:41 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:57:41 volumio kernel: usb 1-1-port2: over-current change #183838 Mar 09 08:57:42 volumio kernel: usb 1-1-port2: over-current change #183839 Mar 09 08:57:42 volumio volumio[15074]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 09 08:57:42 volumio kernel: usb 1-1-port2: over-current change #183840 Mar 09 08:57:42 volumio kernel: usb 1-1-port2: over-current change #183841 Mar 09 08:57:42 volumio kernel: usb 1-1-port2: over-current change #183842 Mar 09 08:57:43 volumio kernel: usb 1-1-port2: over-current change #183843 Mar 09 08:57:43 volumio kernel: usb 1-1-port2: over-current change #183844 Mar 09 08:57:43 volumio kernel: usb 1-1-port2: over-current change #183845 Mar 09 08:57:43 volumio kernel: usb 1-1-port2: over-current change #183846 Mar 09 08:57:44 volumio kernel: usb 1-1-port2: over-current change #183847 Mar 09 08:57:44 volumio kernel: usb 1-1-port2: over-current change #183848 Mar 09 08:57:44 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 08:57:44 volumio kernel: usb 1-1-port2: over-current change #183849 Mar 09 08:57:45 volumio kernel: usb 1-1-port2: over-current change #183850 Mar 09 08:57:45 volumio kernel: usb 1-1-port2: over-current change #183851 Mar 09 08:57:45 volumio kernel: usb 1-1-port2: over-current change #183852 Mar 09 08:57:45 volumio volumio[15074]: info: MYVOLUMIO: Adding device Mar 09 08:57:45 volumio volumio[15074]: info: MYVOLUMIO: Evaluating Server Mar 09 08:57:45 volumio kernel: usb 1-1-port2: over-current change #183853 Mar 09 08:57:46 volumio kernel: usb 1-1-port2: over-current change #183854 Mar 09 08:57:46 volumio kernel: usb 1-1-port2: over-current change #183855 Mar 09 08:57:46 volumio kernel: usb 1-1-port2: over-current change #183856 Mar 09 08:57:46 volumio volumio[15074]: info: Setting Geolocation for MyVolumio to as1 Mar 09 08:57:46 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:57:46 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:57:46 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:57:46 volumio kernel: usb 1-1-port2: over-current change #183857 Mar 09 08:57:47 volumio kernel: usb 1-1-port2: over-current change #183858 Mar 09 08:57:47 volumio volumio[15074]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 09 08:57:47 volumio kernel: usb 1-1-port2: over-current change #183859 Mar 09 08:57:47 volumio kernel: usb 1-1-port2: over-current change #183860 Mar 09 08:57:47 volumio volumio[15074]: info: Updating MyVolumio device info Mar 09 08:57:47 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:57:47 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:57:47 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:57:47 volumio kernel: usb 1-1-port2: over-current change #183861 Mar 09 08:57:48 volumio kernel: usb 1-1-port2: over-current change #183862 Mar 09 08:57:48 volumio volumio[15074]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 09 08:57:48 volumio kernel: usb 1-1-port2: over-current change #183863 Mar 09 08:57:48 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 08:57:48 volumio kernel: usb 1-1-port2: over-current change #183864 Mar 09 08:57:48 volumio kernel: usb 1-1-port2: over-current change #183865 Mar 09 08:57:49 volumio kernel: usb 1-1-port2: over-current change #183866 Mar 09 08:57:49 volumio kernel: usb 1-1-port2: over-current change #183867 Mar 09 08:57:49 volumio kernel: usb 1-1-port2: over-current change #183868 Mar 09 08:57:49 volumio kernel: usb 1-1-port2: over-current change #183869 Mar 09 08:57:50 volumio kernel: usb 1-1-port2: over-current change #183870 Mar 09 08:57:50 volumio kernel: usb 1-1-port2: over-current change #183871 Mar 09 08:57:50 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 09 08:57:50 volumio volumio[15074]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 09 08:57:50 volumio volumio[15074]: info: CoreCommandRouter::volumioGetState Mar 09 08:57:50 volumio volumio[15074]: info: CorePlayQueue::getTrack 0 Mar 09 08:57:50 volumio kernel: usb 1-1-port2: over-current change #183872 Mar 09 08:57:50 volumio kernel: usb 1-1-port2: over-current change #183873 Mar 09 08:57:51 volumio kernel: usb 1-1-port2: over-current change #183874 Mar 09 08:57:51 volumio kernel: usb 1-1-port2: over-current change #183875 Mar 09 08:57:51 volumio kernel: usb 1-1-port2: over-current change #183876 Mar 09 08:57:51 volumio kernel: usb 1-1-port2: over-current change #183877 Mar 09 08:57:52 volumio kernel: usb 1-1-port2: over-current change #183878 Mar 09 08:57:52 volumio volumio[15074]: info: CoreCommandRouter::volumioGetState Mar 09 08:57:52 volumio volumio[15074]: info: CorePlayQueue::getTrack 0 Mar 09 08:57:52 volumio kernel: usb 1-1-port2: over-current change #183879 Mar 09 08:57:52 volumio kernel: usb 1-1-port2: over-current change #183880 Mar 09 08:57:52 volumio kernel: usb 1-1-port2: over-current change #183881 Mar 09 08:57:53 volumio kernel: usb 1-1-port2: over-current change #183882 Mar 09 08:57:53 volumio kernel: usb 1-1-port2: over-current change #183883 Mar 09 08:57:53 volumio kernel: usb 1-1-port2: over-current change #183884 Mar 09 08:57:53 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 09 08:57:53 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:57:53 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 09 08:57:54 volumio kernel: usb 1-1-port2: over-current change #183885 Mar 09 08:57:54 volumio volumio-remote-updater[558]: Test mode enabled Mar 09 08:57:54 volumio volumio-remote-updater[558]: No alpha test mode Mar 09 08:57:54 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 09 08:57:54 volumio kernel: usb 1-1-port2: over-current change #183886 Mar 09 08:57:54 volumio kernel: usb 1-1-port2: over-current change #183887 Mar 09 08:57:54 volumio volumio[15074]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 09 08:57:54 volumio volumio[15074]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 09 08:57:54 volumio volumio[15074]: info: Retrieving Cloud Streaming UI Mar 09 08:57:54 volumio volumio[15074]: info: Getting Tidal Cloud Configuration Mar 09 08:57:54 volumio volumio[15074]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 09 08:57:54 volumio volumio[15074]: info: Getting Qobuz Cloud Configuration Mar 09 08:57:54 volumio volumio[15074]: info: Asking plugin for UI Config Mar 09 08:57:54 volumio volumio[15074]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 09 08:57:54 volumio volumio[15074]: info: Getting Spotify Cloud Configuration Mar 09 08:57:54 volumio volumio[15074]: info: Asking plugin for UI Config Mar 09 08:57:54 volumio volumio[15074]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 09 08:57:54 volumio volumio[15074]: info: Saving Spotify Acccount Mar 09 08:57:54 volumio volumio[15074]: info: Got Tidal Cloud Configuration Mar 09 08:57:54 volumio volumio[15074]: info: Got it Mar 09 08:57:54 volumio volumio[15074]: info: Got it Mar 09 08:57:54 volumio volumio[15074]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 09 08:57:54 volumio volumio[15074]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 09 08:57:54 volumio volumio[15074]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 09 08:57:54 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 09 08:57:54 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 09 08:57:54 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 09 08:57:54 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 09 08:57:54 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 08:57:54 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 08:57:54 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 08:57:54 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 08:57:54 volumio volumio[15074]: info: CoreCommandRouter::volumioGetBrowseSources Mar 09 08:57:54 volumio volumio[15074]: info: CoreCommandRouter::volumioGetBrowseSources Mar 09 08:57:54 volumio volumio[15074]: info: CoreCommandRouter::volumioGetBrowseSources Mar 09 08:57:54 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 08:57:54 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 09 08:57:54 volumio kernel: usb 1-1-port2: over-current change #183888 Mar 09 08:57:54 volumio volumio[15074]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 09 08:57:54 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 09 08:57:55 volumio kernel: usb 1-1-port2: over-current change #183889 Mar 09 08:57:55 volumio kernel: usb 1-1-port2: over-current change #183890 Mar 09 08:57:55 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:55 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:55 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 09 08:57:55 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 09 08:57:55 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 09 08:57:55 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 09 08:57:55 volumio volumio[15074]: info: CoreCommandRouter::volumioGetBrowseSources Mar 09 08:57:55 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 09 08:57:55 volumio kernel: usb 1-1-port2: over-current change #183891 Mar 09 08:57:55 volumio kernel: usb 1-1-port2: over-current change #183892 Mar 09 08:57:56 volumio kernel: usb 1-1-port2: over-current change #183893 Mar 09 08:57:56 volumio kernel: usb 1-1-port2: over-current change #183894 Mar 09 08:57:56 volumio volumio[15074]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 09 08:57:56 volumio volumio[15074]: info: Completed starting MyVolumio Plugin Mar 09 08:57:56 volumio volumio[15074]: [Metrics] CommandRouter: 41s 714.81ms Mar 09 08:57:56 volumio volumio[15074]: info: CoreCommandRouter::volumiosetStartupVolume Mar 09 08:57:56 volumio kernel: usb 1-1-port2: over-current change #183895 Mar 09 08:57:56 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 09 08:57:56 volumio volumio[15074]: info: VolumeController:: Setting startup Volume 35 Mar 09 08:57:56 volumio volumio[15074]: info: VolumeController::SetAlsaVolume35 Mar 09 08:57:56 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 08:57:56 volumio volumio[15074]: info: CoreCommandRouter::Close All Modals sent Mar 09 08:57:56 volumio volumio[15074]: info: CoreCommandRouter::Close All Modals sent Mar 09 08:57:56 volumio volumio[15074]: info: CoreStateMachine::pushState Mar 09 08:57:56 volumio volumio[15074]: info: CorePlayQueue::getTrack 0 Mar 09 08:57:56 volumio volumio[15074]: info: CoreCommandRouter::volumioPushState Mar 09 08:57:56 volumio kernel: usb 1-1-port2: over-current change #183896 Mar 09 08:57:57 volumio kernel: usb 1-1-port2: over-current change #183897 Mar 09 08:57:57 volumio kernel: usb 1-1-port2: over-current change #183898 Mar 09 08:57:57 volumio kernel: usb 1-1-port2: over-current change #183899 Mar 09 08:57:57 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 09 08:57:57 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 09 08:57:57 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 09 08:57:57 volumio kernel: usb 1-1-port2: over-current change #183900 Mar 09 08:57:58 volumio kernel: usb 1-1-port2: over-current change #183901 Mar 09 08:57:58 volumio kernel: usb 1-1-port2: over-current change #183902 Mar 09 08:57:58 volumio kernel: usb 1-1-port2: over-current change #183903 Mar 09 08:57:58 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 08:57:58 volumio kernel: usb 1-1-port2: over-current change #183904 Mar 09 08:57:59 volumio kernel: usb 1-1-port2: over-current change #183905 Mar 09 08:57:59 volumio kernel: usb 1-1-port2: over-current change #183906 Mar 09 08:57:59 volumio kernel: usb 1-1-port2: over-current change #183907 Mar 09 08:57:59 volumio kernel: usb 1-1-port2: over-current change #183908 Mar 09 08:58:00 volumio volumio[15074]: info: CoreCommandRouter::volumioGetState Mar 09 08:58:00 volumio volumio[15074]: info: CorePlayQueue::getTrack 0 Mar 09 08:58:00 volumio kernel: usb 1-1-port2: over-current change #183909 Mar 09 08:58:00 volumio kernel: usb 1-1-port2: over-current change #183910 Mar 09 08:58:00 volumio kernel: usb 1-1-port2: over-current change #183911 Mar 09 08:58:00 volumio kernel: usb 1-1-port2: over-current change #183912 Mar 09 08:58:01 volumio kernel: usb 1-1-port2: over-current change #183913 Mar 09 08:58:01 volumio kernel: usb 1-1-port2: over-current change #183914 Mar 09 08:58:01 volumio kernel: usb 1-1-port2: over-current change #183915 Mar 09 08:58:01 volumio kernel: usb 1-1-port2: over-current change #183916 Mar 09 08:58:02 volumio kernel: usb 1-1-port2: over-current change #183917 Mar 09 08:58:02 volumio kernel: usb 1-1-port2: over-current change #183918 Mar 09 08:58:02 volumio kernel: usb 1-1-port2: over-current change #183919 Mar 09 08:58:02 volumio kernel: usb 1-1-port2: over-current change #183920 Mar 09 08:58:03 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 09 08:58:03 volumio volumio[15074]: info: Preload queue cleared Mar 09 08:58:03 volumio kernel: usb 1-1-port2: over-current change #183921 Mar 09 08:58:03 volumio kernel: usb 1-1-port2: over-current change #183922 Mar 09 08:58:03 volumio volumio[15074]: info: BOOT COMPLETED Mar 09 08:58:03 volumio kernel: usb 1-1-port2: over-current change #183923 Mar 09 08:58:04 volumio kernel: usb 1-1-port2: over-current change #183924 Mar 09 08:58:04 volumio kernel: usb 1-1-port2: over-current change #183925 Mar 09 08:58:04 volumio kernel: usb 1-1-port2: over-current change #183926 Mar 09 08:58:04 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 09 08:58:04 volumio kernel: usb 1-1-port2: over-current change #183927 Mar 09 08:58:04 volumio volumio[15074]: info: Preload queue cleared Mar 09 08:58:05 volumio kernel: usb 1-1-port2: over-current change #183928 Mar 09 08:58:05 volumio kernel: usb 1-1-port2: over-current change #183929 Mar 09 08:58:05 volumio kernel: usb 1-1-port2: over-current change #183930 Mar 09 08:58:05 volumio kernel: usb 1-1-port2: over-current change #183931 Mar 09 08:58:06 volumio kernel: usb 1-1-port2: over-current change #183932 Mar 09 08:58:06 volumio kernel: usb 1-1-port2: over-current change #183933 Mar 09 08:58:06 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 09 08:58:06 volumio kernel: usb 1-1-port2: over-current change #183934 Mar 09 08:58:06 volumio volumio[15074]: info: Preload queue cleared Mar 09 08:58:06 volumio kernel: usb 1-1-port2: over-current change #183935 Mar 09 08:58:07 volumio kernel: usb 1-1-port2: over-current change #183936 Mar 09 08:58:07 volumio kernel: usb 1-1-port2: over-current change #183937 Mar 09 08:58:07 volumio kernel: usb 1-1-port2: over-current change #183938 Mar 09 08:58:07 volumio kernel: usb 1-1-port2: over-current change #183939 Mar 09 08:58:08 volumio kernel: usb 1-1-port2: over-current change #183940 Mar 09 08:58:08 volumio kernel: usb 1-1-port2: over-current change #183941 Mar 09 08:58:08 volumio kernel: usb 1-1-port2: over-current change #183942 Mar 09 08:58:08 volumio kernel: usb 1-1-port2: over-current change #183943 Mar 09 08:58:09 volumio kernel: usb 1-1-port2: over-current change #183944 Mar 09 08:58:09 volumio kernel: usb 1-1-port2: over-current change #183945 Mar 09 08:58:09 volumio kernel: usb 1-1-port2: over-current change #183946 Mar 09 08:58:09 volumio kernel: usb 1-1-port2: over-current change #183947 Mar 09 08:58:09 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 09 08:58:10 volumio volumio[15074]: info: Preload queue cleared Mar 09 08:58:10 volumio kernel: usb 1-1-port2: over-current change #183948 Mar 09 08:58:10 volumio kernel: usb 1-1-port2: over-current change #183949 Mar 09 08:58:10 volumio kernel: usb 1-1-port2: over-current change #183950 Mar 09 08:58:10 volumio kernel: usb 1-1-port2: over-current change #183951 Mar 09 08:58:11 volumio kernel: usb 1-1-port2: over-current change #183952 Mar 09 08:58:11 volumio kernel: usb 1-1-port2: over-current change #183953 Mar 09 08:58:11 volumio kernel: usb 1-1-port2: over-current change #183954 Mar 09 08:58:11 volumio kernel: usb 1-1-port2: over-current change #183955 Mar 09 08:58:12 volumio kernel: usb 1-1-port2: over-current change #183956 Mar 09 08:58:12 volumio volumio[15074]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 09 08:58:12 volumio volumio[15074]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 08:58:12 volumio volumio[15074]: TypeError: Cannot read property 'length' of undefined Mar 09 08:58:12 volumio volumio[15074]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Mar 09 08:58:12 volumio volumio[15074]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Mar 09 08:58:12 volumio volumio[15074]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Mar 09 08:58:12 volumio volumio[15074]: at Parser.emit (events.js:315:20) Mar 09 08:58:12 volumio volumio[15074]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Mar 09 08:58:12 volumio volumio[15074]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Mar 09 08:58:12 volumio volumio[15074]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Mar 09 08:58:12 volumio volumio[15074]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Mar 09 08:58:12 volumio volumio[15074]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Mar 09 08:58:12 volumio volumio[15074]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Mar 09 08:58:12 volumio volumio[15074]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Mar 09 08:58:12 volumio volumio[15074]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Mar 09 08:58:12 volumio volumio[15074]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Mar 09 08:58:12 volumio volumio[15074]: at IncomingMessage.emit (events.js:327:22) Mar 09 08:58:12 volumio volumio[15074]: at endReadableNT (internal/streams/readable.js:1327:12) Mar 09 08:58:12 volumio volumio[15074]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Mar 09 08:58:12 volumio volumio[15074]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 08:58:12 volumio kernel: usb 1-1-port2: over-current change #183957 Mar 09 08:58:12 volumio kernel: usb 1-1-port2: over-current change #183958 Mar 09 08:58:12 volumio sudo[15450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-09 08:57 Mar 09 08:58:12 volumio sudo[15450]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"