-- Logs begin at Mon 2024-05-20 05:32:31 UTC, end at Mon 2024-08-26 06:15:17 UTC. -- Aug 26 06:15:00 volumio systemd[1]: Time has been changed Aug 26 06:15:02 volumio volumio[880]: info: ------------------------------------------- Aug 26 06:15:02 volumio volumio[880]: info: ----- Volumio2 ---- Aug 26 06:15:02 volumio volumio[880]: info: ------------------------------------------- Aug 26 06:15:02 volumio volumio[880]: info: ----- System startup ---- Aug 26 06:15:02 volumio volumio[880]: info: ------------------------------------------- Aug 26 06:15:02 volumio volumio[880]: info: Plugin folders cleanup Aug 26 06:15:02 volumio volumio[880]: info: Scanning into folder /volumio/app/plugins/ Aug 26 06:15:02 volumio volumio[880]: info: Scanning category audio_interface Aug 26 06:15:02 volumio volumio[880]: info: Scanning category miscellanea Aug 26 06:15:02 volumio volumio[880]: info: Scanning category music_service Aug 26 06:15:02 volumio volumio[880]: info: Scanning category plugins.json Aug 26 06:15:02 volumio volumio[880]: info: Scanning category system_controller Aug 26 06:15:02 volumio volumio[880]: info: Scanning category user_interface Aug 26 06:15:02 volumio volumio[880]: info: Scanning into folder /data/plugins/ Aug 26 06:15:02 volumio volumio[880]: info: Plugin folders cleanup completed Aug 26 06:15:02 volumio volumio[880]: info: ------------------------------------------- Aug 26 06:15:02 volumio volumio[880]: info: ----- Core plugins startup ---- Aug 26 06:15:02 volumio volumio[880]: info: ------------------------------------------- Aug 26 06:15:02 volumio volumio[880]: info: Loading plugins from folder /volumio/app/plugins/ Aug 26 06:15:02 volumio volumio[880]: info: Adding plugin upnp to MyMusic Plugins Aug 26 06:15:02 volumio volumio[880]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 26 06:15:02 volumio volumio[880]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 26 06:15:02 volumio volumio[880]: info: Loading plugins from folder /data/plugins/ Aug 26 06:15:02 volumio volumio[880]: info: Loading plugin "system"... Aug 26 06:15:02 volumio volumio[880]: info: Loading plugin "appearance"... Aug 26 06:15:03 volumio volumio-remote-updater[537]: [2024-08-26 06:15:03] [connect] Successful connection Aug 26 06:15:05 volumio volumio[880]: info: Loading plugin "network"... Aug 26 06:15:05 volumio volumio[880]: info: Loading plugin "services"... Aug 26 06:15:05 volumio volumio[880]: info: Loading plugin "alsa_controller"... Aug 26 06:15:06 volumio volumio[880]: info: Loading plugin "wizard"... Aug 26 06:15:06 volumio volumio[880]: info: Loading plugin "volumio_command_line_client"... Aug 26 06:15:06 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 06:15:06 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 06:15:06 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 06:15:06 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 06:15:06 volumio volumio[880]: info: Loading plugin "upnp"... Aug 26 06:15:06 volumio volumio[880]: info: [1724652906242] Starting Upmpd Daemon Aug 26 06:15:06 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 26 06:15:06 volumio volumio[880]: info: Loading plugin "my_music"... Aug 26 06:15:06 volumio volumio[880]: info: Loading plugin "mpd"... Aug 26 06:15:06 volumio volumio[880]: info: Loading plugin "upnp_browser"... Aug 26 06:15:08 volumio volumio[880]: info: Loading plugin "networkfs"... Aug 26 06:15:09 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 26 06:15:09 volumio volumio[880]: info: Loading plugin "alarm-clock"... Aug 26 06:15:09 volumio volumio[880]: info: Loading plugin "airplay_emulation"... Aug 26 06:15:09 volumio volumio[880]: info: Starting Shairport Sync Aug 26 06:15:09 volumio volumio[880]: info: Loading plugin "last_100"... Aug 26 06:15:09 volumio volumio[880]: info: Loading plugin "webradio"... Aug 26 06:15:09 volumio volumio[880]: info: Loading plugin "i2s_dacs"... Aug 26 06:15:09 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 06:15:09 volumio volumio[880]: info: Loading plugin "volumiodiscovery"... Aug 26 06:15:10 volumio volumio[880]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 26 06:15:10 volumio node[880]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 26 06:15:10 volumio node[880]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 26 06:15:10 volumio node[880]: *** WARNING *** For more information see Aug 26 06:15:10 volumio node[880]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 26 06:15:10 volumio node[880]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 26 06:15:10 volumio node[880]: *** WARNING *** For more information see Aug 26 06:15:10 volumio volumio[880]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 26 06:15:10 volumio volumio[880]: *** WARNING *** For more information see Aug 26 06:15:10 volumio volumio[880]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 26 06:15:10 volumio volumio[880]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 26 06:15:10 volumio volumio[880]: *** WARNING *** For more information see Aug 26 06:15:10 volumio volumio[880]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 26 06:15:10 volumio volumio[880]: Discovery: StartAdv! undefined Aug 26 06:15:10 volumio volumio[880]: Discovery: Started advertising... undefined - undefined Aug 26 06:15:10 volumio dbus[565]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' Aug 26 06:15:10 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Aug 26 06:15:10 volumio avahi-daemon[920]: Process 561 died: No such process; trying to remove PID file. (/var/run/avahi-daemon//pid) Aug 26 06:15:10 volumio avahi-daemon[920]: Found user 'avahi' (UID 104) and group 'avahi' (GID 106). Aug 26 06:15:10 volumio avahi-daemon[920]: Successfully dropped root privileges. Aug 26 06:15:10 volumio avahi-daemon[920]: avahi-daemon 0.6.31 starting up. Aug 26 06:15:10 volumio dbus[565]: [system] Successfully activated service 'org.freedesktop.Avahi' Aug 26 06:15:10 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Aug 26 06:15:10 volumio avahi-daemon[920]: Successfully called chroot(). Aug 26 06:15:10 volumio avahi-daemon[920]: Successfully dropped remaining capabilities. Aug 26 06:15:10 volumio avahi-daemon[920]: No service file found in /etc/avahi/services. Aug 26 06:15:10 volumio avahi-daemon[920]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.31. Aug 26 06:15:10 volumio avahi-daemon[920]: New relevant interface eth0.IPv4 for mDNS. Aug 26 06:15:10 volumio avahi-daemon[920]: Network interface enumeration completed. Aug 26 06:15:10 volumio avahi-daemon[920]: Registering new address record for 192.168.0.31 on eth0.IPv4. Aug 26 06:15:10 volumio avahi-daemon[920]: Registering HINFO record with values 'ARMV7L'/'LINUX'. Aug 26 06:15:10 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 26 06:15:10 volumio volumio[880]: info: Loading plugin "albumart"... Aug 26 06:15:10 volumio volumio[880]: info: Plugin example_plugin is not enabled Aug 26 06:15:10 volumio volumio[880]: info: Loading plugin "updater_comm"... Aug 26 06:15:10 volumio volumio[880]: info: Plugin mpdemulation is not enabled Aug 26 06:15:10 volumio volumio[880]: info: Loading plugin "rest_api"... Aug 26 06:15:10 volumio volumio[880]: info: Loading plugin "websocket"... Aug 26 06:15:10 volumio volumio[880]: info: ___________ START PLUGINS ___________ Aug 26 06:15:10 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 06:15:10 volumio volumio[880]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 06:15:10 volumio volumio[880]: info: [1724652910563] CoreMusicLibrary::Adding element Media Servers Aug 26 06:15:10 volumio volumio[880]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 06:15:10 volumio volumio[880]: info: [1724652910616] CoreMusicLibrary::Adding element Last_100 Aug 26 06:15:10 volumio volumio[880]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 06:15:10 volumio volumio[880]: info: [1724652910621] CoreMusicLibrary::Adding element Webradio Aug 26 06:15:10 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 06:15:10 volumio volumio[880]: info: ------------------------------------------- Aug 26 06:15:10 volumio volumio[880]: info: ----- MyVolumio plugins startup ---- Aug 26 06:15:10 volumio volumio[880]: info: ------------------------------------------- Aug 26 06:15:10 volumio volumio[880]: info: Loading plugins from folder /myvolumio/plugins Aug 26 06:15:10 volumio volumio[880]: info: Loading plugins from folder /data/myvolumio/plugins Aug 26 06:15:10 volumio volumio[880]: info: Loading plugin "my_volumio"... Aug 26 06:15:10 volumio volumio[880]: Forking 3 albumart workers Aug 26 06:15:10 volumio avahi-daemon[920]: Server startup complete. Host name is volumio.local. Local service cookie is 3713188296. Aug 26 06:15:11 volumio volumio[880]: info: Loading plugin "streaming_services"... Aug 26 06:15:12 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 06:15:12 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 06:15:12 volumio volumio[880]: info: Streaming services startup Aug 26 06:15:12 volumio volumio[880]: Starting albumart workers Aug 26 06:15:12 volumio volumio[880]: Starting albumart workers Aug 26 06:15:12 volumio volumio[880]: info: Loading i18n strings for locale ko Aug 26 06:15:12 volumio volumio[880]: Updating browse sources language Aug 26 06:15:12 volumio volumio[880]: Cannot find translation for sourceMedia Servers Aug 26 06:15:12 volumio sudo[947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 26 06:15:12 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 06:15:12 volumio volumio[880]: info: CoreCommandRouter::initPlayerControls Aug 26 06:15:12 volumio sudo[947]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 06:15:12 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 06:15:12 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Aug 26 06:15:12 volumio volumio[880]: Starting albumart workers Aug 26 06:15:12 volumio systemd[1]: Stopping Volumio Streaming Daemon... Aug 26 06:15:12 volumio systemd[1]: Starting Volumio Streaming Daemon... Aug 26 06:15:12 volumio sudo[947]: pam_unix(sudo:session): session closed for user root Aug 26 06:15:12 volumio systemd[1]: Started Volumio Streaming Daemon. Aug 26 06:15:12 volumio volumio-streaming-daemon[955]: ############################ Aug 26 06:15:12 volumio volumio-streaming-daemon[955]: # Volumio Streaming Daemon # Aug 26 06:15:12 volumio volumio-streaming-daemon[955]: # Running on port 7777 # Aug 26 06:15:12 volumio volumio-streaming-daemon[955]: ############################ Aug 26 06:15:12 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 06:15:12 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 06:15:12 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 06:15:12 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 06:15:12 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 06:15:12 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 06:15:12 volumio volumio[880]: info: BOOT COMPLETED Aug 26 06:15:12 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 06:15:12 volumio volumio[880]: info: CoreCommandRouter::Close All Modals sent Aug 26 06:15:12 volumio volumio[880]: info: CoreCommandRouter::Close All Modals sent Aug 26 06:15:12 volumio volumio[880]: Express server listening on port 3000 Aug 26 06:15:12 volumio volumio[880]: Volumio Calling Home Aug 26 06:15:13 volumio volumio[880]: info: MyVolumio not started Aug 26 06:15:13 volumio volumio[880]: info: CoreStateMachine::resetVolumioState Aug 26 06:15:13 volumio volumio[880]: info: CoreStateMachine::getcurrentVolume Aug 26 06:15:13 volumio volumio[880]: info: CoreCommandRouter::volumioRetrievevolume Aug 26 06:15:13 volumio volumio[880]: info: CoreStateMachine::updateTrackBlock Aug 26 06:15:13 volumio volumio[880]: info: CorePlayQueue::getTrackBlock Aug 26 06:15:13 volumio volumio[880]: info: Setting Device type: Raspberry PI Aug 26 06:15:13 volumio volumio[880]: info: MPD running with PID562 ,establishing connection Aug 26 06:15:13 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 26 06:15:13 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 26 06:15:14 volumio volumio[880]: info: VolumeController:: Volume=undefined Mute =false Aug 26 06:15:14 volumio volumio[880]: info: CoreStateMachine::pushState Aug 26 06:15:14 volumio volumio[880]: info: CoreStateMachine::getState Aug 26 06:15:14 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Aug 26 06:15:14 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Aug 26 06:15:14 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 06:15:14 volumio volumio[880]: info: Reloading queue from file Aug 26 06:15:14 volumio volumio[880]: info: Aug 26 06:15:14 volumio volumio[880]: info: message= [50@0] {} No such directory, stack=Error: [50@0] {} No such directory Aug 26 06:15:14 volumio volumio[880]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14) Aug 26 06:15:14 volumio volumio[880]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10) Aug 26 06:15:14 volumio volumio[880]: at emitOne (events.js:116:13) Aug 26 06:15:14 volumio volumio[880]: at Socket.emit (events.js:211:7) Aug 26 06:15:14 volumio volumio[880]: at addChunk (_stream_readable.js:263:12) Aug 26 06:15:14 volumio volumio[880]: at readableAddChunk (_stream_readable.js:246:13) Aug 26 06:15:14 volumio volumio[880]: at Socket.Readable.push (_stream_readable.js:208:10) Aug 26 06:15:14 volumio volumio[880]: at Pipe.onread (net.js:607:20) Aug 26 06:15:14 volumio volumio[880]: info: CoreStateMachine::setRepeat null single undefined Aug 26 06:15:14 volumio volumio[880]: info: CoreStateMachine::pushState Aug 26 06:15:14 volumio volumio[880]: info: CoreStateMachine::getState Aug 26 06:15:14 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Aug 26 06:15:14 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Aug 26 06:15:14 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 06:15:14 volumio volumio[880]: info: CoreStateMachine::setRandom null Aug 26 06:15:14 volumio volumio[880]: info: CoreStateMachine::pushState Aug 26 06:15:14 volumio volumio[880]: info: CoreStateMachine::getState Aug 26 06:15:14 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Aug 26 06:15:14 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Aug 26 06:15:14 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 06:15:14 volumio sudo[974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Aug 26 06:15:14 volumio sudo[974]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 06:15:14 volumio sudo[976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Aug 26 06:15:14 volumio sudo[976]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 06:15:14 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Aug 26 06:15:14 volumio systemd[1]: Started ShairportSync AirTunes receiver. Aug 26 06:15:14 volumio sudo[974]: pam_unix(sudo:session): session closed for user root Aug 26 06:15:14 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... Aug 26 06:15:14 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Aug 26 06:15:14 volumio systemd[1]: Started ShairportSync AirTunes receiver. Aug 26 06:15:14 volumio sudo[976]: pam_unix(sudo:session): session closed for user root Aug 26 06:15:14 volumio shairport-sync[990]: Successful Startup Aug 26 06:15:14 volumio volumio[880]: info: Shairport-Sync Started Aug 26 06:15:14 volumio volumio[880]: Error adding Membership: Error: addMembership EINVAL Aug 26 06:15:14 volumio volumio[880]: info: Shairport-Sync Started Aug 26 06:15:14 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Aug 26 06:15:14 volumio volumio[880]: info: CoreStateMachine::getState Aug 26 06:15:14 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Aug 26 06:15:15 volumio volumio[880]: Discovery: ecc TypeError: argument 8 must be an integer (port) Aug 26 06:15:15 volumio volumio[880]: Discovery: StartAdv! false Aug 26 06:15:15 volumio volumio[880]: Discovery: Started advertising... undefined - false Aug 26 06:15:16 volumio volumio[880]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 06:15:16 volumio volumio[880]: TypeError: Cannot read property 'charAt' of undefined Aug 26 06:15:16 volumio volumio[880]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:208:28) Aug 26 06:15:16 volumio volumio[880]: at ontimeout (timers.js:482:11) Aug 26 06:15:16 volumio volumio[880]: at tryOnTimeout (timers.js:317:5) Aug 26 06:15:16 volumio volumio[880]: at Timer.listOnTimeout (timers.js:277:5) Aug 26 06:15:16 volumio volumio[880]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 06:15:17 volumio sudo[1006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-26 06:14 Aug 26 06:15:17 volumio sudo[1006]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="517644ba70b07904a7cfd78158fa2a8b12e9b934" VOLUMIO_FE_VERSION="e2ca9040ba05aea0dd1b326f29b87c86c5061952" VOLUMIO_BE_VERSION="b994356475963dd9c244e0e56678e49304670f11" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun Dec 30 22:53:16 CET 2018" VOLUMIO_VERSION="2.522" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="d1bd47f832048be085b808eb790728e5"