-- Logs begin at Tue 2002-01-01 00:00:34 UTC, end at Mon 2024-08-19 23:51:23 UTC. -- Aug 19 23:50:48 volumio systemd[1]: Time has been changed Aug 19 23:50:51 volumio volumio[1029]: info: ------------------------------------------- Aug 19 23:50:51 volumio volumio[1029]: info: ----- Volumio2 ---- Aug 19 23:50:51 volumio volumio[1029]: info: ------------------------------------------- Aug 19 23:50:51 volumio volumio[1029]: info: ----- System startup ---- Aug 19 23:50:51 volumio volumio[1029]: info: ------------------------------------------- Aug 19 23:50:51 volumio wpa_supplicant[913]: wlan0: WPA: Group rekeying completed with 4e:5e:0c:58:ea:b1 [GTK=CCMP] Aug 19 23:50:55 volumio volumio[1029]: info: MYVOLUMIO Environment detected Aug 19 23:50:56 volumio volumio[1029]: info: Plugin folders cleanup Aug 19 23:50:56 volumio volumio[1029]: info: Scanning into folder /volumio/app/plugins/ Aug 19 23:50:56 volumio volumio[1029]: info: Scanning category audio_interface Aug 19 23:50:56 volumio volumio[1029]: info: Scanning category miscellanea Aug 19 23:50:56 volumio volumio[1029]: info: Scanning category music_service Aug 19 23:50:56 volumio volumio[1029]: info: Scanning category plugins.json Aug 19 23:50:56 volumio volumio[1029]: info: Scanning category system_controller Aug 19 23:50:56 volumio volumio[1029]: info: Scanning category user_interface Aug 19 23:50:56 volumio volumio[1029]: info: Scanning into folder /data/plugins/ Aug 19 23:50:56 volumio volumio[1029]: info: Plugin folders cleanup completed Aug 19 23:50:56 volumio volumio[1029]: info: ------------------------------------------- Aug 19 23:50:56 volumio volumio[1029]: info: ----- Core plugins startup ---- Aug 19 23:50:56 volumio volumio[1029]: info: ------------------------------------------- Aug 19 23:50:56 volumio volumio[1029]: info: Loading plugins from folder /volumio/app/plugins/ Aug 19 23:50:56 volumio volumio[1029]: info: Adding plugin upnp to MyMusic Plugins Aug 19 23:50:56 volumio dbus[716]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' Aug 19 23:50:56 volumio volumio[1029]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 19 23:50:56 volumio volumio[1029]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 19 23:50:56 volumio volumio[1029]: info: Loading plugins from folder /data/plugins/ Aug 19 23:50:56 volumio volumio[1029]: info: Loading plugin "system"... Aug 19 23:50:56 volumio systemd[1]: Starting Bluetooth service... Aug 19 23:50:56 volumio volumio[1029]: info: Loading plugin "appearance"... Aug 19 23:50:56 volumio bluetoothd[1128]: Bluetooth daemon 5.23 Aug 19 23:50:56 volumio dbus[716]: [system] Successfully activated service 'org.bluez' Aug 19 23:50:56 volumio systemd[1]: Started Bluetooth service. Aug 19 23:50:56 volumio kernel: Bluetooth: Core ver 2.19 Aug 19 23:50:56 volumio kernel: NET: Registered protocol family 31 Aug 19 23:50:56 volumio kernel: Bluetooth: HCI device and connection manager initialized Aug 19 23:50:56 volumio kernel: Bluetooth: HCI socket layer initialized Aug 19 23:50:56 volumio kernel: Bluetooth: L2CAP socket layer initialized Aug 19 23:50:56 volumio kernel: Bluetooth: SCO socket layer initialized Aug 19 23:50:56 volumio bluetoothd[1128]: Starting SDP server Aug 19 23:50:56 volumio bluetoothd[1128]: Excluding (cli) sap Aug 19 23:50:56 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Aug 19 23:50:56 volumio kernel: Bluetooth: BNEP filters: protocol multicast Aug 19 23:50:56 volumio kernel: Bluetooth: BNEP socket layer initialized Aug 19 23:50:56 volumio bluetoothd[1128]: Bluetooth management interface 1.7 initialized Aug 19 23:50:56 volumio dbus[716]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' Aug 19 23:50:56 volumio systemd[1]: Starting Hostname Service... Aug 19 23:50:57 volumio systemd-hostnamed[1135]: Warning: nss-myhostname is not installed. Changing the local hostname might make it unresolveable. Please install nss-myhostname! Aug 19 23:50:57 volumio dbus[716]: [system] Successfully activated service 'org.freedesktop.hostname1' Aug 19 23:50:57 volumio volumio-remote-updater[652]: [2024-08-19 23:50:57] [connect] Successful connection Aug 19 23:50:57 volumio systemd[1]: Started Hostname Service. Aug 19 23:51:08 volumio volumio[1029]: info: Loading plugin "network"... Aug 19 23:51:08 volumio volumio[1029]: info: Refreshing Cached IP Addresses Aug 19 23:51:08 volumio volumio[1029]: info: Loading plugin "services"... Aug 19 23:51:08 volumio volumio[1029]: info: Loading plugin "alsa_controller"... Aug 19 23:51:09 volumio sudo[1207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 19 23:51:09 volumio sudo[1207]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 23:51:09 volumio sudo[1205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 19 23:51:09 volumio sudo[1207]: pam_unix(sudo:session): session closed for user root Aug 19 23:51:09 volumio sudo[1205]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 23:51:09 volumio sudo[1205]: pam_unix(sudo:session): session closed for user root Aug 19 23:51:09 volumio volumio[1029]: info: Loading plugin "wizard"... Aug 19 23:51:09 volumio volumio[1029]: info: Loading plugin "volumio_command_line_client"... Aug 19 23:51:09 volumio volumio[1029]: info: Loading plugin "upnp"... Aug 19 23:51:09 volumio volumio[1029]: info: [1724111469681] Starting Upmpd Daemon Aug 19 23:51:09 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 19 23:51:09 volumio volumio[1029]: info: Loading plugin "my_music"... Aug 19 23:51:09 volumio volumio[1029]: info: Loading plugin "mpd"... Aug 19 23:51:12 volumio volumio-remote-updater[652]: [2024-08-19 23:51:12] [connect] Successful connection Aug 19 23:51:12 volumio volumio[1029]: info: Loading plugin "upnp_browser"... Aug 19 23:51:14 volumio volumio[1029]: info: Loading plugin "networkfs"... Aug 19 23:51:15 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 19 23:51:15 volumio volumio[1029]: info: Loading plugin "alarm-clock"... Aug 19 23:51:15 volumio volumio[1029]: info: Loading plugin "airplay_emulation"... Aug 19 23:51:15 volumio volumio[1029]: info: Starting Shairport Sync Aug 19 23:51:15 volumio volumio[1029]: info: Loading plugin "last_100"... Aug 19 23:51:15 volumio volumio[1029]: info: Loading plugin "webradio"... Aug 19 23:51:16 volumio volumio[1029]: info: Loading plugin "i2s_dacs"... Aug 19 23:51:16 volumio volumio[1029]: info: I2S DAC not set, start Auto-detection Aug 19 23:51:16 volumio volumio[1029]: info: Loading plugin "volumiodiscovery"... Aug 19 23:51:16 volumio volumio[1029]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 19 23:51:16 volumio node[1029]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 19 23:51:16 volumio node[1029]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 19 23:51:16 volumio node[1029]: *** WARNING *** For more information see Aug 19 23:51:16 volumio node[1029]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 19 23:51:16 volumio node[1029]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 19 23:51:16 volumio node[1029]: *** WARNING *** For more information see Aug 19 23:51:16 volumio volumio[1029]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 19 23:51:16 volumio volumio[1029]: *** WARNING *** For more information see Aug 19 23:51:16 volumio volumio[1029]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 19 23:51:16 volumio volumio[1029]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 19 23:51:16 volumio volumio[1029]: *** WARNING *** For more information see Aug 19 23:51:16 volumio volumio[1029]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 19 23:51:16 volumio volumio[1029]: Discovery: StartAdv! undefined Aug 19 23:51:16 volumio volumio[1029]: Discovery: Started advertising... undefined - undefined Aug 19 23:51:16 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 19 23:51:16 volumio volumio[1029]: info: Loading plugin "outputs"... Aug 19 23:51:16 volumio volumio[1029]: info: Loading plugin "albumart"... Aug 19 23:51:16 volumio volumio[1029]: info: Plugin example_plugin is not enabled Aug 19 23:51:16 volumio volumio[1029]: info: Loading plugin "inputs"... Aug 19 23:51:16 volumio volumio[1029]: info: Loading plugin "updater_comm"... Aug 19 23:51:17 volumio volumio[1029]: info: Plugin mpdemulation is not enabled Aug 19 23:51:17 volumio volumio[1029]: info: Loading plugin "rest_api"... Aug 19 23:51:17 volumio volumio[1029]: info: Loading plugin "websocket"... Aug 19 23:51:17 volumio volumio[1029]: info: ___________ START PLUGINS ___________ Aug 19 23:51:17 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 23:51:17 volumio volumio[1029]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 19 23:51:17 volumio volumio[1029]: info: [1724111477358] CoreMusicLibrary::Adding element Media Servers Aug 19 23:51:17 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 19 23:51:17 volumio volumio[1029]: Forking 1 albumart workers Aug 19 23:51:19 volumio volumio[1029]: Starting albumart workers Aug 19 23:51:20 volumio volumio[1029]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 19 23:51:20 volumio volumio[1029]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 19 23:51:20 volumio volumio[1029]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 19 23:51:20 volumio volumio[1029]: info: [1724111480816] CoreMusicLibrary::Adding element Last_100 Aug 19 23:51:20 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 19 23:51:20 volumio volumio[1029]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 19 23:51:20 volumio volumio[1029]: info: [1724111480825] CoreMusicLibrary::Adding element Webradio Aug 19 23:51:20 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 19 23:51:20 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 19 23:51:20 volumio volumio[1029]: info: ------------------------------------------- Aug 19 23:51:20 volumio volumio[1029]: info: ----- MyVolumio plugins startup ---- Aug 19 23:51:20 volumio volumio[1029]: info: ------------------------------------------- Aug 19 23:51:20 volumio volumio[1029]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 19 23:51:21 volumio volumio[1029]: info: Loading i18n strings for locale ru Aug 19 23:51:21 volumio volumio[1029]: Updating browse sources language Aug 19 23:51:21 volumio volumio[1029]: Cannot find translation for sourceMedia Servers Aug 19 23:51:21 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 19 23:51:21 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 23:51:21 volumio volumio[1029]: info: CoreCommandRouter::initPlayerControls Aug 19 23:51:21 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:51:21 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Aug 19 23:51:21 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:51:21 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:51:21 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:51:21 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:51:21 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:51:21 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 23:51:21 volumio volumio[1029]: info: BOOT COMPLETED Aug 19 23:51:21 volumio volumio[1029]: [Metrics] CommandRouter: 30s 682.91ms Aug 19 23:51:21 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 23:51:21 volumio volumio[1029]: info: CoreCommandRouter::Close All Modals sent Aug 19 23:51:21 volumio volumio[1029]: info: CoreCommandRouter::Close All Modals sent Aug 19 23:51:21 volumio volumio[1029]: Express server listening on port 3000 Aug 19 23:51:21 volumio volumio[1029]: [Metrics] WebUI: 33s 386.55ms Aug 19 23:51:21 volumio volumio[1029]: info: Setting Device type: x86 Aug 19 23:51:21 volumio volumio[1029]: Volumio Calling Home Aug 19 23:51:21 volumio volumio[1029]: info: CoreStateMachine::resetVolumioState Aug 19 23:51:21 volumio volumio[1029]: info: CoreStateMachine::getcurrentVolume Aug 19 23:51:21 volumio volumio[1029]: info: CoreCommandRouter::volumioRetrievevolume Aug 19 23:51:22 volumio volumio[1029]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 19 23:51:22 volumio volumio[1029]: TypeError: Cannot read property 'charAt' of undefined Aug 19 23:51:22 volumio volumio[1029]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Aug 19 23:51:22 volumio volumio[1029]: at ontimeout (timers.js:482:11) Aug 19 23:51:22 volumio volumio[1029]: at tryOnTimeout (timers.js:317:5) Aug 19 23:51:22 volumio volumio[1029]: at Timer.listOnTimeout (timers.js:277:5) Aug 19 23:51:22 volumio volumio[1029]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 19 23:51:22 volumio kernel: perf interrupt took too long (2548 > 2500), lowering kernel.perf_event_max_sample_rate to 50000 Aug 19 23:51:23 volumio sudo[1291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-19 23:50 Aug 19 23:51:23 volumio sudo[1291]: 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="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 11:49:13 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="62511ec23417b6085328d5bda7373a0c"