-- Logs begin at Thu 2024-03-28 05:15:36 UTC, end at Sat 2024-05-04 00:50:45 UTC. -- May 04 00:49:59 volumio2 systemd[1]: Time has been changed May 04 00:49:59 volumio2 volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 00:49:59 volumio2 volumio[888]: info: Loading plugin "volumiodiscovery"... May 04 00:49:59 volumio2 volumio[888]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 04 00:49:59 volumio2 node[888]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 04 00:49:59 volumio2 node[888]: *** WARNING *** Please fix your application to use the native API of Avahi! May 04 00:49:59 volumio2 node[888]: *** WARNING *** For more information see May 04 00:49:59 volumio2 node[888]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 04 00:49:59 volumio2 node[888]: *** WARNING *** Please fix your application to use the native API of Avahi! May 04 00:49:59 volumio2 node[888]: *** WARNING *** For more information see May 04 00:49:59 volumio2 volumio[888]: *** WARNING *** Please fix your application to use the native API of Avahi! May 04 00:49:59 volumio2 volumio[888]: *** WARNING *** For more information see May 04 00:49:59 volumio2 volumio[888]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 04 00:49:59 volumio2 volumio[888]: *** WARNING *** Please fix your application to use the native API of Avahi! May 04 00:49:59 volumio2 volumio[888]: *** WARNING *** For more information see May 04 00:49:59 volumio2 volumio[888]: info: Applying required configuration parameters for plugin volumiodiscovery May 04 00:49:59 volumio2 volumio[888]: Discovery: StartAdv! undefined May 04 00:49:59 volumio2 volumio[888]: Discovery: Started advertising... Volumio2 - undefined May 04 00:49:59 volumio2 volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 04 00:49:59 volumio2 volumio[888]: info: Loading plugin "albumart"... May 04 00:49:59 volumio2 volumio[888]: info: Plugin example_plugin is not enabled May 04 00:49:59 volumio2 volumio[888]: info: Loading plugin "updater_comm"... May 04 00:49:59 volumio2 volumio[888]: info: Plugin mpdemulation is not enabled May 04 00:49:59 volumio2 volumio[888]: info: Loading plugin "rest_api"... May 04 00:49:59 volumio2 volumio[888]: info: Loading plugin "websocket"... May 04 00:49:59 volumio2 volumio[888]: info: ___________ START PLUGINS ___________ May 04 00:49:59 volumio2 volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 00:49:59 volumio2 volumio[888]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 00:49:59 volumio2 volumio[888]: info: [1714783799388] CoreMusicLibrary::Adding element Media Servers May 04 00:49:59 volumio2 volumio[888]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 00:49:59 volumio2 volumio[888]: info: [1714783799419] CoreMusicLibrary::Adding element Last_100 May 04 00:49:59 volumio2 volumio[888]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 00:49:59 volumio2 volumio[888]: info: [1714783799422] CoreMusicLibrary::Adding element Webradio May 04 00:49:59 volumio2 volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 00:49:59 volumio2 volumio[888]: info: ------------------------------------------- May 04 00:49:59 volumio2 volumio[888]: info: ----- MyVolumio plugins startup ---- May 04 00:49:59 volumio2 volumio[888]: info: ------------------------------------------- May 04 00:49:59 volumio2 volumio[888]: info: Loading plugins from folder /myvolumio/plugins May 04 00:49:59 volumio2 volumio[888]: info: Loading plugins from folder /data/myvolumio/plugins May 04 00:49:59 volumio2 volumio[888]: info: Loading plugin "my_volumio"... May 04 00:49:59 volumio2 volumio[888]: Forking 3 albumart workers May 04 00:49:59 volumio2 volumio-remote-updater[634]: [2024-05-04 00:49:59] [connect] Successful connection May 04 00:50:00 volumio2 volumio[888]: info: Loading plugin "streaming_services"... May 04 00:50:00 volumio2 volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 00:50:00 volumio2 volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 00:50:00 volumio2 volumio[888]: info: Streaming services startup May 04 00:50:00 volumio2 volumio[888]: info: Loading i18n strings for locale ja May 04 00:50:00 volumio2 volumio[888]: Updating browse sources language May 04 00:50:00 volumio2 volumio[888]: Cannot find translation for sourceMedia Servers May 04 00:50:00 volumio2 volumio[888]: Starting albumart workers May 04 00:50:00 volumio2 volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 00:50:00 volumio2 volumio[888]: info: CoreCommandRouter::initPlayerControls May 04 00:50:00 volumio2 sudo[958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 04 00:50:00 volumio2 volumio[888]: Starting albumart workers May 04 00:50:00 volumio2 volumio[888]: Starting albumart workers May 04 00:50:00 volumio2 sudo[958]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 00:50:00 volumio2 volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 00:50:00 volumio2 volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards May 04 00:50:00 volumio2 systemd[1]: Stopping Volumio Streaming Daemon... May 04 00:50:00 volumio2 systemd[1]: Starting Volumio Streaming Daemon... May 04 00:50:00 volumio2 systemd[1]: Started Volumio Streaming Daemon. May 04 00:50:00 volumio2 sudo[958]: pam_unix(sudo:session): session closed for user root May 04 00:50:00 volumio2 volumio-streaming-daemon[965]: ############################ May 04 00:50:00 volumio2 volumio-streaming-daemon[965]: # Volumio Streaming Daemon # May 04 00:50:00 volumio2 volumio-streaming-daemon[965]: # Running on port 7777 # May 04 00:50:00 volumio2 volumio-streaming-daemon[965]: ############################ May 04 00:50:00 volumio2 volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 00:50:00 volumio2 volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 00:50:00 volumio2 volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 00:50:00 volumio2 volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 00:50:00 volumio2 volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 00:50:00 volumio2 volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 00:50:00 volumio2 volumio[888]: info: BOOT COMPLETED May 04 00:50:00 volumio2 volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 00:50:05 volumio2 volumio[888]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo May 04 00:50:06 volumio2 volumio[888]: info: CoreCommandRouter::Close All Modals sent May 04 00:50:06 volumio2 volumio[888]: info: CoreCommandRouter::Close All Modals sent May 04 00:50:06 volumio2 volumio[888]: Express server listening on port 3000 May 04 00:50:06 volumio2 volumio[888]: Volumio Calling Home May 04 00:50:06 volumio2 volumio[888]: info: MyVolumio not started May 04 00:50:06 volumio2 volumio[888]: info: CoreStateMachine::resetVolumioState May 04 00:50:06 volumio2 volumio[888]: info: CoreStateMachine::getcurrentVolume May 04 00:50:06 volumio2 volumio[888]: info: CoreCommandRouter::volumioRetrievevolume May 04 00:50:06 volumio2 volumio[888]: info: CoreStateMachine::updateTrackBlock May 04 00:50:06 volumio2 volumio[888]: info: CorePlayQueue::getTrackBlock May 04 00:50:06 volumio2 volumio[888]: info: CoreStateMachine::pushState May 04 00:50:06 volumio2 volumio[888]: info: CoreStateMachine::getState May 04 00:50:06 volumio2 volumio[888]: info: CorePlayQueue::getTrack 0 May 04 00:50:06 volumio2 volumio[888]: info: CoreCommandRouter::volumioPushState May 04 00:50:06 volumio2 volumio[888]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 00:50:06 volumio2 volumio[888]: info: Setting Device type: Raspberry PI May 04 00:50:06 volumio2 volumio[888]: info: MPD running with PID656 ,establishing connection May 04 00:50:06 volumio2 volumio[888]: info: Reloading queue from file May 04 00:50:06 volumio2 volumio[888]: info: CoreStateMachine::setRepeat true single undefined May 04 00:50:06 volumio2 volumio[888]: info: CoreStateMachine::pushState May 04 00:50:06 volumio2 volumio[888]: info: CoreStateMachine::getState May 04 00:50:06 volumio2 volumio[888]: info: CorePlayQueue::getTrack 0 May 04 00:50:06 volumio2 volumio[888]: info: CoreCommandRouter::volumioPushState May 04 00:50:06 volumio2 volumio[888]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 00:50:06 volumio2 volumio[888]: info: CoreStateMachine::setRandom false May 04 00:50:06 volumio2 volumio[888]: info: CoreStateMachine::pushState May 04 00:50:06 volumio2 volumio[888]: info: CoreStateMachine::getState May 04 00:50:06 volumio2 volumio[888]: info: CorePlayQueue::getTrack 0 May 04 00:50:06 volumio2 volumio[888]: info: CoreCommandRouter::volumioPushState May 04 00:50:06 volumio2 volumio[888]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 00:50:06 volumio2 sudo[987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay May 04 00:50:06 volumio2 sudo[987]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 00:50:06 volumio2 sudo[989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay May 04 00:50:06 volumio2 systemd[1]: Starting ShairportSync AirTunes receiver... May 04 00:50:06 volumio2 systemd[1]: Started ShairportSync AirTunes receiver. May 04 00:50:06 volumio2 sudo[989]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 00:50:06 volumio2 sudo[987]: pam_unix(sudo:session): session closed for user root May 04 00:50:06 volumio2 volumio[888]: Discovery: adding c3f55f8f-5671-4e44-b350-d8f79bc00a38 May 04 00:50:06 volumio2 volumio[888]: info: mDNS: Found device Volumio2 May 04 00:50:06 volumio2 systemd[1]: Stopping ShairportSync AirTunes receiver... May 04 00:50:06 volumio2 systemd[1]: Starting ShairportSync AirTunes receiver... May 04 00:50:06 volumio2 systemd[1]: Started ShairportSync AirTunes receiver. May 04 00:50:06 volumio2 sudo[989]: pam_unix(sudo:session): session closed for user root May 04 00:50:06 volumio2 shairport-sync[1003]: Successful Startup May 04 00:50:06 volumio2 volumio[888]: Discovery: this is already registered, c3f55f8f-5671-4e44-b350-d8f79bc00a38 May 04 00:50:06 volumio2 volumio[888]: info: mDNS: Found device Volumio2 May 04 00:50:06 volumio2 sudo[1009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 04 00:50:06 volumio2 sudo[1009]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 00:50:06 volumio2 sudo[1009]: pam_unix(sudo:session): session closed for user root May 04 00:50:06 volumio2 sudo[1014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 04 00:50:06 volumio2 sudo[1014]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 00:50:06 volumio2 sudo[1014]: pam_unix(sudo:session): session closed for user root May 04 00:50:06 volumio2 volumio[888]: info: Shairport-Sync Started May 04 00:50:06 volumio2 volumio[888]: Error adding Membership: Error: addMembership EINVAL May 04 00:50:06 volumio2 volumio[888]: info: Shairport-Sync Started May 04 00:50:06 volumio2 volumio[888]: info: CoreCommandRouter::volumioGetState May 04 00:50:06 volumio2 volumio[888]: info: CoreStateMachine::getState May 04 00:50:06 volumio2 volumio[888]: info: CorePlayQueue::getTrack 0 May 04 00:50:06 volumio2 sudo[1030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 04 00:50:06 volumio2 sudo[1030]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 00:50:06 volumio2 sudo[1030]: pam_unix(sudo:session): session closed for user root May 04 00:50:07 volumio2 volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 04 00:50:07 volumio2 volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 04 00:50:07 volumio2 sudo[1041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 04 00:50:07 volumio2 sudo[1041]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 00:50:07 volumio2 sudo[1041]: pam_unix(sudo:session): session closed for user root May 04 00:50:07 volumio2 sudo[1048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 04 00:50:07 volumio2 sudo[1048]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 00:50:07 volumio2 systemd[1]: Starting UPnP Renderer front-end to MPD... May 04 00:50:07 volumio2 systemd[1]: Started UPnP Renderer front-end to MPD. May 04 00:50:07 volumio2 sudo[1048]: pam_unix(sudo:session): session closed for user root May 04 00:50:07 volumio2 volumio[888]: info: Upmpdcli Daemon Started May 04 00:50:14 volumio2 volumio-remote-updater[634]: [2024-05-04 00:50:14] [connect] Successful connection May 04 00:50:14 volumio2 volumio-remote-updater[634]: [2024-05-04 00:50:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1714783814 101 May 04 00:50:17 volumio2 udisks-glue[632]: error: Timeout May 04 00:50:21 volumio2 volumio[888]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.502&uuid=2aa75a37b5f51af22818b512296603de" http://updates.volumio.org:7070/downloader-v1/track-device May 04 00:50:21 volumio2 volumio[888]: % Total % Received % Xferd Average Speed Time Time Time Current May 04 00:50:21 volumio2 volumio[888]: Dload Upload Total Spent Left Speed May 04 00:50:21 volumio2 volumio[888]: [551B blob data] May 04 00:50:21 volumio2 volumio[888]: retrying in 5 seconds, trial 0 May 04 00:50:21 volumio2 volumio[888]: Volumio Calling Home May 04 00:50:29 volumio2 volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 00:50:29 volumio2 volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 00:50:35 volumio2 volumio[888]: info: CoreCommandRouter::volumioGetBrowseSources May 04 00:50:35 volumio2 volumio[888]: info: CoreCommandRouter::volumioGetState May 04 00:50:35 volumio2 volumio[888]: info: CoreStateMachine::getState May 04 00:50:35 volumio2 volumio[888]: info: CorePlayQueue::getTrack 0 May 04 00:50:35 volumio2 sudo[1065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 04 00:50:35 volumio2 sudo[1065]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 00:50:35 volumio2 volumio[888]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 04 00:50:35 volumio2 volumio[888]: info: Listing playlists May 04 00:50:35 volumio2 volumio[888]: info: CoreCommandRouter::volumioGetQueue May 04 00:50:35 volumio2 volumio[888]: info: CoreStateMachine::getQueue May 04 00:50:35 volumio2 sudo[1065]: pam_unix(sudo:session): session closed for user root May 04 00:50:35 volumio2 volumio[888]: info: CorePlayQueue::getQueue May 04 00:50:35 volumio2 sudo[1071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 04 00:50:35 volumio2 sudo[1071]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 00:50:35 volumio2 sudo[1071]: pam_unix(sudo:session): session closed for user root May 04 00:50:37 volumio2 volumio[888]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.502&uuid=2aa75a37b5f51af22818b512296603de" http://updates.volumio.org:7070/downloader-v1/track-device May 04 00:50:37 volumio2 volumio[888]: % Total % Received % Xferd Average Speed Time Time Time Current May 04 00:50:37 volumio2 volumio[888]: Dload Upload Total Spent Left Speed May 04 00:50:37 volumio2 volumio[888]: [551B blob data] May 04 00:50:37 volumio2 volumio[888]: retrying in 5 seconds, trial 1 May 04 00:50:37 volumio2 volumio[888]: Volumio Calling Home May 04 00:50:44 volumio2 volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 04 00:50:44 volumio2 volumio[888]: info: CURURI: artists:// May 04 00:50:44 volumio2 volumio[888]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 00:50:44 volumio2 volumio[888]: { AssertionError [ERR_ASSERTION]: false == true May 04 00:50:44 volumio2 volumio[888]: at MpdClient.sendCommand (/volumio/app/plugins/music_service/mpd/lib/mpd.js:92:9) May 04 00:50:44 volumio2 volumio[888]: at ControllerMpd.listArtists (/volumio/app/plugins/music_service/mpd/index.js:3254:17) May 04 00:50:44 volumio2 volumio[888]: at ControllerMpd.handleBrowseUri (/volumio/app/plugins/music_service/mpd/index.js:2926:29) May 04 00:50:44 volumio2 volumio[888]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1013:29) May 04 00:50:44 volumio2 volumio[888]: at CoreMusicLibrary.executeBrowseSource (/volumio/app/musiclibrary.js:319:39) May 04 00:50:44 volumio2 volumio[888]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:363:32) May 04 00:50:44 volumio2 volumio[888]: at emitTwo (events.js:126:13) May 04 00:50:44 volumio2 volumio[888]: at Socket.emit (events.js:214:7) May 04 00:50:44 volumio2 volumio[888]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 04 00:50:44 volumio2 volumio[888]: at _combinedTickCallback (internal/process/next_tick.js:131:7) May 04 00:50:44 volumio2 volumio[888]: at process._tickCallback (internal/process/next_tick.js:180:9) May 04 00:50:44 volumio2 volumio[888]: generatedMessage: true, May 04 00:50:44 volumio2 volumio[888]: name: 'AssertionError [ERR_ASSERTION]', May 04 00:50:44 volumio2 volumio[888]: code: 'ERR_ASSERTION', May 04 00:50:44 volumio2 volumio[888]: actual: false, May 04 00:50:44 volumio2 volumio[888]: expected: true, May 04 00:50:44 volumio2 volumio[888]: operator: '==' } May 04 00:50:44 volumio2 volumio[888]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 00:50:45 volumio2 sudo[1093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-04 00:49 May 04 00:50:45 volumio2 sudo[1093]: 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="c52efa8090336a1aa08c5caa4d93eb926a1a7bed" VOLUMIO_FE_VERSION="97a49ba890ceb3037f74abfccc14d256050d3ef6" VOLUMIO_BE_VERSION="8bb3137b5d493584edfbdbea0fb0075f47d9c7f6" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 31 12:58:00 CET 2018" VOLUMIO_VERSION="2.502" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="d0847620f095b456556d6c09b0a4a5d4"