-- Logs begin at Tue 2025-03-11 08:30:25 UTC, end at Wed 2025-03-12 06:57:00 UTC. -- Mar 12 06:56:00 volumio.dlna volumio[5014]: info: CALLMETHOD: miscellanea my_music updateMusicLibraryBrowseSourcesVisibility [object Object] Mar 12 06:56:00 volumio.dlna volumio[5014]: info: CoreCommandRouter::executeOnPlugin: my_music , updateMusicLibraryBrowseSourcesVisibility Mar 12 06:56:00 volumio.dlna volumio[5014]: info: CoreCommandRouter::volumioUpdateToBrowseSources Mar 12 06:56:00 volumio.dlna volumio[5014]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 06:56:02 volumio.dlna volumio[5014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 06:56:06 volumio.dlna volumio[5014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 06:56:10 volumio.dlna volumio[5014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 06:56:14 volumio.dlna volumio[5014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 12 06:56:18 volumio.dlna volumio[5014]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreStateMachine::ClearQueue Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreStateMachine::stop Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 12 06:56:27 volumio.dlna volumio[5014]: UNSET VOLATILE Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CorePlayQueue::clearPlayQueue Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CorePlayQueue::saveQueue Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreStateMachine::pushEmptyState Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreCommandRouter::volumioPushState Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreCommandRouter::volumioPushQueue Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreStateMachine::addQueueItems Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CorePlayQueue::addQueueItems Mar 12 06:56:27 volumio.dlna volumio[5014]: info: Adding Item to queue: cue://INTERNAL/方大同2007-未来[港版][WAV+CUE]/方大同.-.[未来 Wonderland](2007)[WAV].cue@10 Mar 12 06:56:27 volumio.dlna volumio[5014]: info: Exploding uri cue://INTERNAL/方大同2007-未来[港版][WAV+CUE]/方大同.-.[未来 Wonderland](2007)[WAV].cue@10 in service mpd Mar 12 06:56:27 volumio.dlna sudo[13707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 12 06:56:27 volumio.dlna sudo[13707]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 06:56:27 volumio.dlna sudo[13707]: pam_unix(sudo:session): session closed for user root Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CorePlayQueue::saveQueue Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreCommandRouter::volumioPushQueue Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreStateMachine::updateTrackBlock Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CorePlayQueue::getTrackBlock Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreCommandRouter::volumioPlay Mar 12 06:56:27 volumio.dlna volumio[5014]: UNSET VOLATILE Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreStateMachine::play index 0 Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreStateMachine::stop Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 12 06:56:27 volumio.dlna volumio[5014]: UNSET VOLATILE Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreStateMachine::play index undefined Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CorePlayQueue::getTrack 0 Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreStateMachine::startPlaybackTimer Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CorePlayQueue::getTrack 0 Mar 12 06:56:27 volumio.dlna volumio[5014]: info: ControllerMpd::sendMpdCommand stop Mar 12 06:56:27 volumio.dlna volumio[5014]: info: sending command... Mar 12 06:56:27 volumio.dlna volumio[5014]: info: parsing response... Mar 12 06:56:27 volumio.dlna volumio[5014]: info: ControllerMpd::sendMpdCommand clear Mar 12 06:56:27 volumio.dlna volumio[5014]: info: sending command... Mar 12 06:56:27 volumio.dlna volumio[5014]: info: Mar 12 06:56:27 volumio.dlna volumio[5014]: ---------------------------- MPD announces system playlist update Mar 12 06:56:27 volumio.dlna volumio[5014]: info: Ignoring MPD Status Update Mar 12 06:56:27 volumio.dlna volumio[5014]: info: parsing response... Mar 12 06:56:27 volumio.dlna volumio[5014]: info: ControllerMpd::sendMpdCommand load "INTERNAL/方大同2007-未来[港版][WAV+CUE]/方大同.-.[未来 Wonderland](2007)[WAV].cue" Mar 12 06:56:27 volumio.dlna volumio[5014]: info: sending command... Mar 12 06:56:27 volumio.dlna volumio[5014]: info: Mar 12 06:56:27 volumio.dlna volumio[5014]: info: Mar 12 06:56:27 volumio.dlna volumio[5014]: ---------------------------- MPD announces system playlist update Mar 12 06:56:27 volumio.dlna volumio[5014]: info: Ignoring MPD Status Update Mar 12 06:56:27 volumio.dlna volumio[5014]: info: ------------------------------ 8ms Mar 12 06:56:27 volumio.dlna volumio[5014]: info: parsing response... Mar 12 06:56:27 volumio.dlna volumio[5014]: info: ControllerMpd::sendMpdCommand play Mar 12 06:56:27 volumio.dlna volumio[5014]: info: sending command... Mar 12 06:56:27 volumio.dlna volumio[5014]: info: ------------------------------ 8ms Mar 12 06:56:27 volumio.dlna volumio[5014]: info: parsing response... Mar 12 06:56:27 volumio.dlna volumio[5014]: info: Mar 12 06:56:27 volumio.dlna volumio[5014]: ---------------------------- MPD announces state update: player Mar 12 06:56:27 volumio.dlna volumio[5014]: info: ControllerMpd::getState Mar 12 06:56:27 volumio.dlna volumio[5014]: info: ControllerMpd::sendMpdCommand status Mar 12 06:56:27 volumio.dlna volumio[5014]: info: sending command... Mar 12 06:56:27 volumio.dlna volumio[5014]: info: Mar 12 06:56:27 volumio.dlna volumio[5014]: ---------------------------- MPD announces state update: player Mar 12 06:56:27 volumio.dlna volumio[5014]: info: parsing response... Mar 12 06:56:27 volumio.dlna volumio[5014]: info: ControllerMpd::getState Mar 12 06:56:27 volumio.dlna volumio[5014]: info: ControllerMpd::sendMpdCommand status Mar 12 06:56:27 volumio.dlna volumio[5014]: info: ControllerMpd::parseState Mar 12 06:56:27 volumio.dlna volumio[5014]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 12 06:56:27 volumio.dlna volumio[5014]: info: sending command... Mar 12 06:56:27 volumio.dlna volumio[5014]: info: sending command... Mar 12 06:56:27 volumio.dlna volumio[5014]: info: parsing response... Mar 12 06:56:27 volumio.dlna volumio[5014]: info: parsing response... Mar 12 06:56:27 volumio.dlna volumio[5014]: info: ControllerMpd::parseState Mar 12 06:56:27 volumio.dlna volumio[5014]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 12 06:56:27 volumio.dlna volumio[5014]: info: ControllerMpd::parseTrackInfo Mar 12 06:56:27 volumio.dlna volumio[5014]: info: sending command... Mar 12 06:56:27 volumio.dlna volumio[5014]: info: ControllerMpd::pushState Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreCommandRouter::servicePushState Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreStateMachine::syncState Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CorePlayQueue::getTrack 0 Mar 12 06:56:27 volumio.dlna volumio[5014]: info: STATE SERVICE {"status":"play","position":10,"seek":0,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"爱爱爱 (Acoustic Version)","artist":"方大同","album":"未来 Wonderland","uri":"INTERNAL/方大同2007-未来[港版][WAV+CUE]/方大同.-.[未来 Wonderland](2007)[WAV].wav","trackType":"wav"} Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CURRENT POSITION 0 Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreStateMachine::syncState stateService play Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreStateMachine::syncState currentStatus stop Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreStateMachine::pushState Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreStateMachine::getState Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CorePlayQueue::getTrack 0 Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreCommandRouter::volumioPushState Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 06:56:27 volumio.dlna sudo[13710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 12 06:56:27 volumio.dlna volumio[5014]: info: ------------------------------ 77ms Mar 12 06:56:27 volumio.dlna sudo[13710]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 06:56:27 volumio.dlna volumio[5014]: info: parsing response... Mar 12 06:56:27 volumio.dlna volumio[5014]: info: ControllerMpd::parseTrackInfo Mar 12 06:56:27 volumio.dlna volumio[5014]: info: ControllerMpd::pushState Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreCommandRouter::servicePushState Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreStateMachine::syncState Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CorePlayQueue::getTrack 0 Mar 12 06:56:27 volumio.dlna volumio[5014]: info: STATE SERVICE {"status":"play","position":10,"seek":464,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"爱爱爱 (Acoustic Version)","artist":"方大同","album":"未来 Wonderland","uri":"INTERNAL/方大同2007-未来[港版][WAV+CUE]/方大同.-.[未来 Wonderland](2007)[WAV].wav","trackType":"wav"} Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CURRENT POSITION 0 Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreStateMachine::syncState stateService play Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreStateMachine::syncState currentStatus play Mar 12 06:56:27 volumio.dlna volumio[5014]: info: Received an update from plugin. extracting info from payload Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreStateMachine::pushState Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreStateMachine::getState Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CorePlayQueue::getTrack 0 Mar 12 06:56:27 volumio.dlna sudo[13710]: pam_unix(sudo:session): session closed for user root Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreCommandRouter::volumioPushState Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreStateMachine::pushState Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreStateMachine::getState Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CorePlayQueue::getTrack 0 Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreCommandRouter::volumioPushState Mar 12 06:56:27 volumio.dlna volumio[5014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 06:56:27 volumio.dlna sudo[13713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 12 06:56:27 volumio.dlna sudo[13713]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 06:56:27 volumio.dlna sudo[13713]: pam_unix(sudo:session): session closed for user root Mar 12 06:56:27 volumio.dlna volumio[5014]: info: ------------------------------ 115ms Mar 12 06:56:27 volumio.dlna sudo[13716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 12 06:56:27 volumio.dlna sudo[13716]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 06:56:27 volumio.dlna sudo[13716]: pam_unix(sudo:session): session closed for user root Mar 12 06:56:34 volumio.dlna volumio[5014]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 12 06:56:34 volumio.dlna volumio[5014]: info: CoreStateMachine::ClearQueue Mar 12 06:56:34 volumio.dlna volumio[5014]: info: CoreStateMachine::stop Mar 12 06:56:34 volumio.dlna volumio[5014]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 12 06:56:34 volumio.dlna volumio[5014]: UNSET VOLATILE Mar 12 06:56:34 volumio.dlna volumio[5014]: info: CoreStateMachine::stPlaybackTimer Mar 12 06:56:34 volumio.dlna volumio[5014]: info: CoreStateMachine::updateTrackBlock Mar 12 06:56:34 volumio.dlna volumio[5014]: info: CorePlayQueue::getTrackBlock Mar 12 06:56:34 volumio.dlna volumio[5014]: info: CoreStateMachine::pushState Mar 12 06:56:34 volumio.dlna volumio[5014]: info: CoreStateMachine::getState Mar 12 06:56:34 volumio.dlna volumio[5014]: info: CorePlayQueue::getTrack 0 Mar 12 06:56:34 volumio.dlna volumio[5014]: info: CoreCommandRouter::volumioPushState Mar 12 06:56:34 volumio.dlna volumio[5014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 06:56:34 volumio.dlna volumio[5014]: info: CoreStateMachine::serviceStop Mar 12 06:56:34 volumio.dlna volumio[5014]: info: CorePlayQueue::getTrack 0 Mar 12 06:56:34 volumio.dlna volumio[5014]: info: CoreCommandRouter::serviceStop Mar 12 06:56:34 volumio.dlna volumio[5014]: info: ControllerMpd::stop Mar 12 06:56:34 volumio.dlna volumio[5014]: info: ControllerMpd::sendMpdCommand stop Mar 12 06:56:34 volumio.dlna volumio[5014]: info: CorePlayQueue::clearPlayQueue Mar 12 06:56:34 volumio.dlna volumio[5014]: info: CorePlayQueue::saveQueue Mar 12 06:56:34 volumio.dlna volumio[5014]: info: CoreStateMachine::pushEmptyState Mar 12 06:56:34 volumio.dlna volumio[5014]: info: CoreCommandRouter::volumioPushState Mar 12 06:56:34 volumio.dlna volumio[5014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 06:56:34 volumio.dlna sudo[13739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 12 06:56:34 volumio.dlna sudo[13739]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 06:56:34 volumio.dlna sudo[13739]: pam_unix(sudo:session): session closed for user root Mar 12 06:56:34 volumio.dlna volumio[5014]: info: CoreCommandRouter::volumioPushQueue Mar 12 06:56:34 volumio.dlna volumio[5014]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 12 06:56:34 volumio.dlna volumio[5014]: ReferenceError: self is not defined Mar 12 06:56:34 volumio.dlna volumio[5014]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:495:6) Mar 12 06:56:34 volumio.dlna volumio[5014]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:78:31) Mar 12 06:56:34 volumio.dlna volumio[5014]: at emitTwo (events.js:126:13) Mar 12 06:56:34 volumio.dlna volumio[5014]: at Socket.emit (events.js:214:7) Mar 12 06:56:34 volumio.dlna volumio[5014]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Mar 12 06:56:34 volumio.dlna volumio[5014]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Mar 12 06:56:34 volumio.dlna volumio[5014]: at process._tickCallback (internal/process/next_tick.js:180:9) Mar 12 06:56:34 volumio.dlna volumio[5014]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 12 06:56:34 volumio.dlna sudo[13742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 12 06:56:34 volumio.dlna sudo[13742]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 06:56:34 volumio.dlna sudo[13742]: pam_unix(sudo:session): session closed for user root Mar 12 06:56:35 volumio.dlna sudo[13754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-12 06:55 Mar 12 06:56:35 volumio.dlna sudo[13754]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 06:56:35 volumio.dlna sudo[13754]: pam_unix(sudo:session): session closed for user root Mar 12 06:56:37 volumio.dlna volumio-remote-updater[4558]: [2025-03-12 06:56:37] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Mar 12 06:56:37 volumio.dlna volumio-remote-updater[4558]: [2025-03-12 06:56:37] [error] handle_read_frame error: websocketpp.transport:2 (Underlying Transport Error) Mar 12 06:56:37 volumio.dlna volumio-remote-updater[4558]: [2025-03-12 06:56:37] [disconnect] Disconnect close local:[1006,Underlying Transport Error] remote:[1006] Mar 12 06:56:37 volumio.dlna systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Mar 12 06:56:37 volumio.dlna systemd[1]: Unit volumio.service entered failed state. Mar 12 06:56:37 volumio.dlna systemd[1]: Starting dynamicswap service... Mar 12 06:56:37 volumio.dlna systemd[1]: Started dynamicswap service. Mar 12 06:56:37 volumio.dlna systemd[1]: volumio.service holdoff time over, scheduling restart. Mar 12 06:56:37 volumio.dlna systemd[1]: Starting dynamicswap service... Mar 12 06:56:37 volumio.dlna systemd[1]: Started dynamicswap service. Mar 12 06:56:37 volumio.dlna systemd[1]: Stopping Volumio Backend Module... Mar 12 06:56:37 volumio.dlna systemd[1]: Starting Volumio Backend Module... Mar 12 06:56:37 volumio.dlna systemd[1]: Started Volumio Backend Module. Mar 12 06:56:38 volumio.dlna nmbd[5277]: [2025/03/12 06:56:38.632587, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 12 06:56:38 volumio.dlna nmbd[5277]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.221 for name WORKGROUP<1d>. Mar 12 06:56:38 volumio.dlna nmbd[5277]: This response was from IP 192.168.2.2, reporting an IP address of 192.168.2.2. Mar 12 06:56:39 volumio.dlna volumio[13781]: info: ------------------------------------------- Mar 12 06:56:39 volumio.dlna volumio[13781]: info: ----- Volumio2 ---- Mar 12 06:56:39 volumio.dlna volumio[13781]: info: ------------------------------------------- Mar 12 06:56:39 volumio.dlna volumio[13781]: info: ----- System startup ---- Mar 12 06:56:39 volumio.dlna volumio[13781]: info: ------------------------------------------- Mar 12 06:56:39 volumio.dlna volumio[13781]: info: MYVOLUMIO Environment detected Mar 12 06:56:39 volumio.dlna volumio[13781]: info: Plugin folders cleanup Mar 12 06:56:39 volumio.dlna volumio[13781]: info: Scanning into folder /volumio/app/plugins/ Mar 12 06:56:39 volumio.dlna volumio[13781]: info: Scanning category audio_interface Mar 12 06:56:39 volumio.dlna volumio[13781]: info: Scanning category miscellanea Mar 12 06:56:39 volumio.dlna volumio[13781]: info: Scanning category music_service Mar 12 06:56:39 volumio.dlna volumio[13781]: info: Scanning category plugins.json Mar 12 06:56:39 volumio.dlna volumio[13781]: info: Scanning category system_controller Mar 12 06:56:39 volumio.dlna volumio[13781]: info: Scanning category user_interface Mar 12 06:56:39 volumio.dlna volumio[13781]: info: Scanning into folder /data/plugins/ Mar 12 06:56:39 volumio.dlna volumio[13781]: info: Scanning category music_service Mar 12 06:56:39 volumio.dlna volumio[13781]: info: Plugin folders cleanup completed Mar 12 06:56:39 volumio.dlna volumio[13781]: info: ------------------------------------------- Mar 12 06:56:39 volumio.dlna volumio[13781]: info: ----- Core plugins startup ---- Mar 12 06:56:39 volumio.dlna volumio[13781]: info: ------------------------------------------- Mar 12 06:56:39 volumio.dlna volumio[13781]: info: Loading plugins from folder /volumio/app/plugins/ Mar 12 06:56:39 volumio.dlna volumio[13781]: info: Adding plugin upnp to MyMusic Plugins Mar 12 06:56:39 volumio.dlna volumio[13781]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 12 06:56:39 volumio.dlna volumio[13781]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 12 06:56:39 volumio.dlna volumio[13781]: info: Loading plugins from folder /data/plugins/ Mar 12 06:56:39 volumio.dlna volumio[13781]: info: Loading plugin "system"... Mar 12 06:56:39 volumio.dlna volumio[13781]: info: Loading plugin "appearance"... Mar 12 06:56:40 volumio.dlna volumio[13781]: info: Loading plugin "network"... Mar 12 06:56:40 volumio.dlna volumio[13781]: info: Loading plugin "services"... Mar 12 06:56:40 volumio.dlna volumio[13781]: info: Loading plugin "alsa_controller"... Mar 12 06:56:40 volumio.dlna volumio[13781]: info: Loading plugin "wizard"... Mar 12 06:56:40 volumio.dlna volumio[13781]: info: Loading plugin "volumio_command_line_client"... Mar 12 06:56:40 volumio.dlna volumio[13781]: info: Loading plugin "upnp"... Mar 12 06:56:40 volumio.dlna volumio[13781]: info: [1741762600514] Starting Upmpd Daemon Mar 12 06:56:40 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 12 06:56:40 volumio.dlna volumio[13781]: info: Loading plugin "my_music"... Mar 12 06:56:40 volumio.dlna volumio[13781]: info: Loading plugin "mpd"... Mar 12 06:56:40 volumio.dlna volumio[13781]: info: Loading plugin "upnp_browser"... Mar 12 06:56:41 volumio.dlna volumio[13781]: info: Loading plugin "networkfs"... Mar 12 06:56:41 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 12 06:56:41 volumio.dlna volumio[13781]: info: Loading plugin "alarm-clock"... Mar 12 06:56:42 volumio.dlna volumio[13781]: info: Plugin airplay_emulation is not enabled Mar 12 06:56:42 volumio.dlna volumio[13781]: info: Loading plugin "last_100"... Mar 12 06:56:42 volumio.dlna volumio[13781]: info: Loading plugin "webradio"... Mar 12 06:56:42 volumio.dlna volumio-remote-updater[4558]: [2025-03-12 06:56:42] [connect] Successful connection Mar 12 06:56:42 volumio.dlna volumio[13781]: info: Loading plugin "i2s_dacs"... Mar 12 06:56:42 volumio.dlna volumio[13781]: info: I2S DAC not set, start Auto-detection Mar 12 06:56:42 volumio.dlna volumio[13781]: info: Loading plugin "volumiodiscovery"... Mar 12 06:56:42 volumio.dlna volumio[13781]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 12 06:56:42 volumio.dlna node[13781]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 12 06:56:42 volumio.dlna node[13781]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 12 06:56:42 volumio.dlna node[13781]: *** WARNING *** For more information see Mar 12 06:56:42 volumio.dlna node[13781]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 12 06:56:42 volumio.dlna node[13781]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 12 06:56:42 volumio.dlna node[13781]: *** WARNING *** For more information see Mar 12 06:56:42 volumio.dlna volumio[13781]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 12 06:56:42 volumio.dlna volumio[13781]: *** WARNING *** For more information see Mar 12 06:56:42 volumio.dlna volumio[13781]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 12 06:56:42 volumio.dlna volumio[13781]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 12 06:56:42 volumio.dlna volumio[13781]: *** WARNING *** For more information see Mar 12 06:56:42 volumio.dlna volumio[13781]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 12 06:56:42 volumio.dlna volumio[13781]: Discovery: StartAdv! undefined Mar 12 06:56:42 volumio.dlna volumio[13781]: Discovery: Started advertising... Volumio - undefined Mar 12 06:56:42 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 12 06:56:42 volumio.dlna volumio[13781]: info: Loading plugin "outputs"... Mar 12 06:56:42 volumio.dlna volumio[13781]: info: Loading plugin "albumart"... Mar 12 06:56:42 volumio.dlna volumio[13781]: info: Plugin example_plugin is not enabled Mar 12 06:56:42 volumio.dlna volumio[13781]: info: Loading plugin "inputs"... Mar 12 06:56:42 volumio.dlna volumio[13781]: info: Loading plugin "updater_comm"... Mar 12 06:56:42 volumio.dlna volumio[13781]: info: Plugin mpdemulation is not enabled Mar 12 06:56:42 volumio.dlna volumio[13781]: info: Loading plugin "rest_api"... Mar 12 06:56:42 volumio.dlna volumio[13781]: info: Loading plugin "websocket"... Mar 12 06:56:42 volumio.dlna volumio[13781]: info: Loading plugin "squeezelite"... Mar 12 06:56:42 volumio.dlna volumio[13781]: Forking 3 albumart workers Mar 12 06:56:42 volumio.dlna volumio[13781]: info: Squeezelite initiated Mar 12 06:56:42 volumio.dlna volumio[13781]: info: ___________ START PLUGINS ___________ Mar 12 06:56:42 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 12 06:56:42 volumio.dlna volumio[13781]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 12 06:56:42 volumio.dlna volumio[13781]: info: [1741762602881] CoreMusicLibrary::Adding element Media Servers Mar 12 06:56:42 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 06:56:42 volumio.dlna volumio[13781]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 12 06:56:42 volumio.dlna volumio[13781]: info: [1741762602919] CoreMusicLibrary::Adding element Last_100 Mar 12 06:56:42 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 06:56:42 volumio.dlna volumio[13781]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 12 06:56:42 volumio.dlna volumio[13781]: info: [1741762602924] CoreMusicLibrary::Adding element Webradio Mar 12 06:56:42 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 06:56:42 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 12 06:56:42 volumio.dlna volumio[13781]: info: ------------------------------------------- Mar 12 06:56:42 volumio.dlna volumio[13781]: info: ----- MyVolumio plugins startup ---- Mar 12 06:56:42 volumio.dlna volumio[13781]: info: ------------------------------------------- Mar 12 06:56:42 volumio.dlna volumio[13781]: info: Loading plugins from folder /myvolumio/plugins Mar 12 06:56:42 volumio.dlna volumio[13781]: info: Loading plugins from folder /data/myvolumio/plugins Mar 12 06:56:42 volumio.dlna volumio[13781]: info: Loading plugin "cd_controller"... Mar 12 06:56:42 volumio.dlna sudo[13839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Mar 12 06:56:43 volumio.dlna sudo[13839]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 06:56:43 volumio.dlna systemd[1]: Reloading. Mar 12 06:56:43 volumio.dlna volumio[13781]: info: Loading plugin "streaming_services"... Mar 12 06:56:43 volumio.dlna systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Mar 12 06:56:43 volumio.dlna systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Mar 12 06:56:43 volumio.dlna systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Mar 12 06:56:43 volumio.dlna systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Mar 12 06:56:43 volumio.dlna volumio[13781]: info: Loading plugin "my_volumio"... Mar 12 06:56:43 volumio.dlna sudo[13839]: pam_unix(sudo:session): session closed for user root Mar 12 06:56:43 volumio.dlna volumio[13781]: Starting albumart workers Mar 12 06:56:43 volumio.dlna volumio[13781]: Starting albumart workers Mar 12 06:56:43 volumio.dlna volumio[13781]: Starting albumart workers Mar 12 06:56:44 volumio.dlna volumio[13781]: info: Adding CD REST API Endpoints Mar 12 06:56:44 volumio.dlna volumio[13781]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller Mar 12 06:56:44 volumio.dlna volumio[13781]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller Mar 12 06:56:44 volumio.dlna volumio[13781]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller Mar 12 06:56:44 volumio.dlna volumio[13781]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Mar 12 06:56:44 volumio.dlna volumio[13781]: info: Detecting CD presence Mar 12 06:56:44 volumio.dlna volumio[13781]: info: Streaming services startup Mar 12 06:56:44 volumio.dlna volumio[13781]: info: Starting Streaming Daemon Mar 12 06:56:44 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 06:56:44 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 06:56:44 volumio.dlna volumio[13781]: info: Starting MyVolumio Remote Streaming Endpoints Mar 12 06:56:44 volumio.dlna sudo[13855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 12 06:56:44 volumio.dlna sudo[13855]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 06:56:44 volumio.dlna systemd[1]: Stopping Volumio Streaming Daemon... Mar 12 06:56:44 volumio.dlna volumio[13781]: info: Loading i18n strings for locale zh Mar 12 06:56:44 volumio.dlna volumio[13781]: Updating browse sources language Mar 12 06:56:44 volumio.dlna volumio[13781]: Cannot find translation for sourceMedia Servers Mar 12 06:56:44 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 06:56:44 volumio.dlna systemd[1]: Starting Volumio Streaming Daemon... Mar 12 06:56:44 volumio.dlna systemd[1]: Started Volumio Streaming Daemon. Mar 12 06:56:44 volumio.dlna sudo[13855]: pam_unix(sudo:session): session closed for user root Mar 12 06:56:44 volumio.dlna volumio-streaming-daemon[13858]: ############################ Mar 12 06:56:44 volumio.dlna volumio-streaming-daemon[13858]: # Volumio Streaming Daemon # Mar 12 06:56:44 volumio.dlna volumio-streaming-daemon[13858]: # Running on port 7777 # Mar 12 06:56:44 volumio.dlna volumio-streaming-daemon[13858]: ############################ Mar 12 06:56:44 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 12 06:56:44 volumio.dlna volumio[13781]: info: CoreCommandRouter::initPlayerControls Mar 12 06:56:44 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 06:56:44 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Mar 12 06:56:44 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 06:56:44 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 06:56:44 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 06:56:44 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 06:56:44 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 06:56:44 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 12 06:56:44 volumio.dlna volumio[13781]: info: BOOT COMPLETED Mar 12 06:56:44 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 06:56:44 volumio.dlna volumio[13781]: aplay: main:722: audio open error: Device or resource busy Mar 12 06:56:44 volumio.dlna volumio[13781]: Cannot play startup sound Mar 12 06:56:44 volumio.dlna volumio[13781]: info: CoreCommandRouter::Close All Modals sent Mar 12 06:56:44 volumio.dlna volumio[13781]: info: CoreCommandRouter::Close All Modals sent Mar 12 06:56:44 volumio.dlna volumio[13781]: Express server listening on port 3000 Mar 12 06:56:44 volumio.dlna volumio[13781]: Volumio Calling Home Mar 12 06:56:44 volumio.dlna volumio[13781]: info: CoreStateMachine::resetVolumioState Mar 12 06:56:44 volumio.dlna volumio[13781]: info: CoreStateMachine::getcurrentVolume Mar 12 06:56:44 volumio.dlna volumio[13781]: info: CoreCommandRouter::volumioRetrievevolume Mar 12 06:56:44 volumio.dlna volumio[13781]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Mar 12 06:56:44 volumio.dlna volumio[13781]: info: Setting Device type: Amlogic Mar 12 06:56:44 volumio.dlna volumio[13781]: info: squeezelite reloaded Mar 12 06:56:44 volumio.dlna sudo[13878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart squeezelite Mar 12 06:56:44 volumio.dlna sudo[13878]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 06:56:44 volumio.dlna systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... Mar 12 06:56:45 volumio.dlna volumio[13781]: info: MPD running with PID4660 ,establishing connection Mar 12 06:56:45 volumio.dlna sudo[13881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Mar 12 06:56:45 volumio.dlna sudo[13881]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 06:56:45 volumio.dlna sudo[13881]: pam_unix(sudo:session): session closed for user root Mar 12 06:56:45 volumio.dlna volumio-remote-updater[4558]: [2025-03-12 06:56:45] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1741762602 101 Mar 12 06:56:45 volumio.dlna volumio[13781]: info: VolumeController:: Volume=36 Mute =false Mar 12 06:56:45 volumio.dlna volumio[13781]: info: CoreStateMachine::pushState Mar 12 06:56:45 volumio.dlna volumio[13781]: info: CoreStateMachine::getState Mar 12 06:56:45 volumio.dlna volumio[13781]: info: CorePlayQueue::getTrack 0 Mar 12 06:56:45 volumio.dlna volumio[13781]: info: CoreCommandRouter::volumioPushState Mar 12 06:56:45 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 06:56:45 volumio.dlna volumio[13781]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Mar 12 06:56:45 volumio.dlna volumio[13781]: Error: Could not open file `/dev/i2c-1' or `/dev/i2c/1': No such file or directory Mar 12 06:56:45 volumio.dlna volumio[13781]: info: Cannot read play queue from file Mar 12 06:56:45 volumio.dlna volumio[13781]: info: message= [50@0] {} No such directory, stack=Error: [50@0] {} No such directory Mar 12 06:56:45 volumio.dlna volumio[13781]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14) Mar 12 06:56:45 volumio.dlna volumio[13781]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10) Mar 12 06:56:45 volumio.dlna volumio[13781]: at emitOne (events.js:116:13) Mar 12 06:56:45 volumio.dlna volumio[13781]: at Socket.emit (events.js:211:7) Mar 12 06:56:45 volumio.dlna volumio[13781]: at addChunk (_stream_readable.js:263:12) Mar 12 06:56:45 volumio.dlna volumio[13781]: at readableAddChunk (_stream_readable.js:246:13) Mar 12 06:56:45 volumio.dlna volumio[13781]: at Socket.Readable.push (_stream_readable.js:208:10) Mar 12 06:56:45 volumio.dlna volumio[13781]: at Pipe.onread (net.js:607:20) Mar 12 06:56:45 volumio.dlna sudo[13878]: pam_unix(sudo:session): session closed for user root Mar 12 06:56:45 volumio.dlna systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Mar 12 06:56:45 volumio.dlna systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Mar 12 06:56:45 volumio.dlna squeezelite[13884]: [06:56:45.308310] test_open:281 playback open error: Device or resource busy Mar 12 06:56:45 volumio.dlna squeezelite[13884]: [06:56:45.308530] output_init_common:401 unable to open output device: hw:CARD=Audio,DEV=0 Mar 12 06:56:45 volumio.dlna volumio[13781]: info: CoreStateMachine::setRepeat false single undefined Mar 12 06:56:45 volumio.dlna volumio[13781]: info: CoreStateMachine::pushState Mar 12 06:56:45 volumio.dlna volumio[13781]: info: CoreStateMachine::getState Mar 12 06:56:45 volumio.dlna volumio[13781]: info: CorePlayQueue::getTrack 0 Mar 12 06:56:45 volumio.dlna volumio[13781]: info: CoreCommandRouter::volumioPushState Mar 12 06:56:45 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 06:56:45 volumio.dlna volumio[13781]: info: CoreStateMachine::setRandom false Mar 12 06:56:45 volumio.dlna volumio[13781]: info: CoreStateMachine::pushState Mar 12 06:56:45 volumio.dlna volumio[13781]: info: CoreStateMachine::getState Mar 12 06:56:45 volumio.dlna volumio[13781]: info: CorePlayQueue::getTrack 0 Mar 12 06:56:45 volumio.dlna volumio[13781]: info: CoreCommandRouter::volumioPushState Mar 12 06:56:45 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 06:56:45 volumio.dlna volumio[13781]: info: squeezelite started Mar 12 06:56:45 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 12 06:56:45 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 12 06:56:45 volumio.dlna systemd[1]: squeezelite.service holdoff time over, scheduling restart. Mar 12 06:56:45 volumio.dlna systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... Mar 12 06:56:45 volumio.dlna systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Mar 12 06:56:45 volumio.dlna systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Mar 12 06:56:45 volumio.dlna volumio[13781]: Discovery: adding 789ed7c3-8304-4492-9a03-8ddd1a880922 Mar 12 06:56:45 volumio.dlna volumio[13781]: info: mDNS: Found device Volumio Mar 12 06:56:45 volumio.dlna squeezelite[13891]: [06:56:45.465878] test_open:281 playback open error: Device or resource busy Mar 12 06:56:45 volumio.dlna squeezelite[13891]: [06:56:45.466908] output_init_common:401 unable to open output device: hw:CARD=Audio,DEV=0 Mar 12 06:56:45 volumio.dlna sudo[13895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 12 06:56:45 volumio.dlna sudo[13895]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 06:56:45 volumio.dlna sudo[13895]: pam_unix(sudo:session): session closed for user root Mar 12 06:56:45 volumio.dlna systemd[1]: squeezelite.service holdoff time over, scheduling restart. Mar 12 06:56:45 volumio.dlna systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... Mar 12 06:56:45 volumio.dlna systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Mar 12 06:56:45 volumio.dlna systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Mar 12 06:56:45 volumio.dlna squeezelite[13897]: [06:56:45.612134] test_open:281 playback open error: Device or resource busy Mar 12 06:56:45 volumio.dlna squeezelite[13897]: [06:56:45.613279] output_init_common:401 unable to open output device: hw:CARD=Audio,DEV=0 Mar 12 06:56:45 volumio.dlna volumio[13781]: Creating Thumbnail for file 1 : /data/backgrounds/thumbnail-61a456a3c7843cef52a40e275ef15a3f.png Mar 12 06:56:45 volumio.dlna volumio[13781]: Error: Invalid file signature Mar 12 06:56:45 volumio.dlna volumio[13781]: at module.exports.Parser._parseSignature (/volumio/node_modules/pngjs/lib/parser.js:53:18) Mar 12 06:56:45 volumio.dlna volumio[13781]: at module.exports.ChunkStream._processRead (/volumio/node_modules/pngjs/lib/chunkstream.js:174:13) Mar 12 06:56:45 volumio.dlna volumio[13781]: at module.exports.ChunkStream._process (/volumio/node_modules/pngjs/lib/chunkstream.js:193:14) Mar 12 06:56:45 volumio.dlna volumio[13781]: at module.exports.ChunkStream.write (/volumio/node_modules/pngjs/lib/chunkstream.js:61:8) Mar 12 06:56:45 volumio.dlna volumio[13781]: at module.exports.ChunkStream.end (/volumio/node_modules/pngjs/lib/chunkstream.js:74:10) Mar 12 06:56:45 volumio.dlna volumio[13781]: at exports.PNG.PNG.end (/volumio/node_modules/pngjs/lib/png.js:98:16) Mar 12 06:56:45 volumio.dlna volumio[13781]: at exports.PNG.PNG.parse (/volumio/node_modules/pngjs/lib/png.js:88:8) Mar 12 06:56:45 volumio.dlna volumio[13781]: at Jimp.parseBitmap (/volumio/node_modules/jimp/index.js:297:17) Mar 12 06:56:45 volumio.dlna volumio[13781]: at /volumio/node_modules/jimp/index.js:202:29 Mar 12 06:56:45 volumio.dlna volumio[13781]: at FSReqWrap.readFileAfterClose [as oncomplete] (fs.js:511:3) Mar 12 06:56:45 volumio.dlna systemd[1]: squeezelite.service holdoff time over, scheduling restart. Mar 12 06:56:45 volumio.dlna systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... Mar 12 06:56:45 volumio.dlna systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Mar 12 06:56:45 volumio.dlna systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Mar 12 06:56:45 volumio.dlna squeezelite[13900]: [06:56:45.859247] test_open:281 playback open error: Device or resource busy Mar 12 06:56:45 volumio.dlna squeezelite[13900]: [06:56:45.860205] output_init_common:401 unable to open output device: hw:CARD=Audio,DEV=0 Mar 12 06:56:46 volumio.dlna volumio[13781]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 12 06:56:46 volumio.dlna volumio[13781]: info: MYVOLUMIO: Adding device Mar 12 06:56:46 volumio.dlna volumio[13781]: info: MYVOLUMIO: Evaluating Server Mar 12 06:56:46 volumio.dlna systemd[1]: squeezelite.service holdoff time over, scheduling restart. Mar 12 06:56:46 volumio.dlna systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... Mar 12 06:56:46 volumio.dlna systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Mar 12 06:56:46 volumio.dlna systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Mar 12 06:56:46 volumio.dlna squeezelite[13903]: [06:56:46.110514] test_open:281 playback open error: Device or resource busy Mar 12 06:56:46 volumio.dlna squeezelite[13903]: [06:56:46.111633] output_init_common:401 unable to open output device: hw:CARD=Audio,DEV=0 Mar 12 06:56:46 volumio.dlna volumio[13781]: info: CoreCommandRouter::volumioGetState Mar 12 06:56:46 volumio.dlna volumio[13781]: info: CoreStateMachine::getState Mar 12 06:56:46 volumio.dlna volumio[13781]: info: CorePlayQueue::getTrack 0 Mar 12 06:56:46 volumio.dlna sudo[13907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 12 06:56:46 volumio.dlna sudo[13907]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 06:56:46 volumio.dlna sudo[13907]: pam_unix(sudo:session): session closed for user root Mar 12 06:56:46 volumio.dlna systemd[1]: squeezelite.service holdoff time over, scheduling restart. Mar 12 06:56:46 volumio.dlna systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... Mar 12 06:56:46 volumio.dlna systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Mar 12 06:56:46 volumio.dlna systemd[1]: squeezelite.service start request repeated too quickly, refusing to start. Mar 12 06:56:46 volumio.dlna systemd[1]: Failed to start Squeezelite lightweight headless Squeezebox emulator. Mar 12 06:56:46 volumio.dlna systemd[1]: Unit squeezelite.service entered failed state. Mar 12 06:56:47 volumio.dlna volumio[13781]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD Mar 12 06:56:47 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 06:56:47 volumio.dlna volumio[13781]: info: MyVolumio status changed Mar 12 06:56:47 volumio.dlna volumio[13781]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Mar 12 06:56:47 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 06:56:47 volumio.dlna volumio[13781]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Mar 12 06:56:47 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 06:56:47 volumio.dlna volumio[13781]: info: Streaming services startup Mar 12 06:56:47 volumio.dlna volumio[13781]: info: Re-Starting Streaming Daemon Mar 12 06:56:47 volumio.dlna volumio[13781]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD Mar 12 06:56:47 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 06:56:47 volumio.dlna volumio[13781]: info: Removing browser output: myVolumio user plan is not superstar Mar 12 06:56:47 volumio.dlna volumio[13781]: info: Removing audio output: Mar 12 06:56:47 volumio.dlna volumio[13781]: info: Stoppping Tunnel 1 Mar 12 06:56:47 volumio.dlna sudo[13933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 12 06:56:47 volumio.dlna sudo[13933]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 06:56:47 volumio.dlna volumio-streaming-daemon[13858]: Restarting daemon Mar 12 06:56:47 volumio.dlna systemd[1]: Stopped MyVolumio SSH Tunnel. Mar 12 06:56:47 volumio.dlna sudo[13933]: pam_unix(sudo:session): session closed for user root Mar 12 06:56:47 volumio.dlna volumio[13781]: info: Remote SSH Stopped Mar 12 06:56:47 volumio.dlna volumio[13781]: info: Fetching Streaming Services browse cache Mar 12 06:56:47 volumio.dlna volumio[13781]: info: CoreCommandRouter::volumioGetVisibleSources Mar 12 06:56:47 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 06:56:47 volumio.dlna volumio[13781]: info: CoreCommandRouter::volumioGetState Mar 12 06:56:47 volumio.dlna volumio[13781]: info: CoreStateMachine::getState Mar 12 06:56:47 volumio.dlna volumio[13781]: info: CorePlayQueue::getTrack 0 Mar 12 06:56:48 volumio.dlna sudo[13937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 12 06:56:48 volumio.dlna sudo[13937]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 06:56:48 volumio.dlna sudo[13937]: pam_unix(sudo:session): session closed for user root Mar 12 06:56:48 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 12 06:56:48 volumio.dlna volumio[13781]: info: Listing playlists Mar 12 06:56:48 volumio.dlna volumio[13781]: info: CoreCommandRouter::volumioGetQueue Mar 12 06:56:48 volumio.dlna volumio[13781]: info: CoreStateMachine::getQueue Mar 12 06:56:48 volumio.dlna volumio[13781]: info: CorePlayQueue::getQueue Mar 12 06:56:48 volumio.dlna sudo[13940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 12 06:56:48 volumio.dlna sudo[13940]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 06:56:48 volumio.dlna sudo[13940]: pam_unix(sudo:session): session closed for user root Mar 12 06:56:48 volumio.dlna volumio[13781]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Mar 12 06:56:48 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 06:56:48 volumio.dlna volumio[13781]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Mar 12 06:56:48 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 06:56:48 volumio.dlna volumio[13781]: info: Setting Geolocation for MyVolumio to au1 Mar 12 06:56:48 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 06:56:48 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 06:56:48 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 06:56:48 volumio.dlna volumio[13781]: info: Fetching Streaming Services browse cache Mar 12 06:56:48 volumio.dlna volumio[13781]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Mar 12 06:56:48 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 06:56:48 volumio.dlna volumio[13781]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Mar 12 06:56:48 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 06:56:49 volumio.dlna volumio[13781]: info: Updating MyVolumio device info Mar 12 06:56:49 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 06:56:49 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 06:56:49 volumio.dlna volumio[13781]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 06:56:50 volumio.dlna sudo[13952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 12 06:56:50 volumio.dlna sudo[13952]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 06:56:50 volumio.dlna sudo[13952]: pam_unix(sudo:session): session closed for user root Mar 12 06:56:50 volumio.dlna sudo[13955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 12 06:56:50 volumio.dlna sudo[13955]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 06:56:50 volumio.dlna systemd[1]: Started UPnP Renderer front-end to MPD. Mar 12 06:56:50 volumio.dlna sudo[13955]: pam_unix(sudo:session): session closed for user root Mar 12 06:56:50 volumio.dlna volumio[13781]: info: Upmpdcli Daemon Started Mar 12 06:56:55 volumio.dlna volumio[13781]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=vim1&variante=volumio&version=2.603&uuid=f1517821a7fb7a966ec971dc3d8b07ea" http://updates.volumio.org:7070/downloader-v1/track-device Mar 12 06:56:55 volumio.dlna volumio[13781]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 12 06:56:55 volumio.dlna volumio[13781]: Dload Upload Total Spent Left Speed Mar 12 06:56:55 volumio.dlna volumio[13781]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (52) Empty reply from server Mar 12 06:56:55 volumio.dlna volumio[13781]: retrying in 5 seconds, trial 0 Mar 12 06:56:55 volumio.dlna volumio[13781]: Volumio Calling Home Mar 12 06:56:59 volumio.dlna volumio[13781]: info: CoreCommandRouter::volumioAddQueueItems Mar 12 06:56:59 volumio.dlna volumio[13781]: info: CoreStateMachine::addQueueItems Mar 12 06:56:59 volumio.dlna volumio[13781]: info: CorePlayQueue::addQueueItems Mar 12 06:56:59 volumio.dlna volumio[13781]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 12 06:56:59 volumio.dlna volumio[13781]: TypeError: Cannot read property 'startsWith' of undefined Mar 12 06:56:59 volumio.dlna volumio[13781]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:158:25) Mar 12 06:56:59 volumio.dlna volumio[13781]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:249:24) Mar 12 06:56:59 volumio.dlna volumio[13781]: at CoreCommandRouter.addQueueItems (/volumio/app/index.js:463:27) Mar 12 06:56:59 volumio.dlna volumio[13781]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:109:36) Mar 12 06:56:59 volumio.dlna volumio[13781]: at emitTwo (events.js:126:13) Mar 12 06:56:59 volumio.dlna volumio[13781]: at Socket.emit (events.js:214:7) Mar 12 06:56:59 volumio.dlna volumio[13781]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Mar 12 06:56:59 volumio.dlna volumio[13781]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Mar 12 06:56:59 volumio.dlna volumio[13781]: at process._tickCallback (internal/process/next_tick.js:180:9) Mar 12 06:56:59 volumio.dlna volumio[13781]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 12 06:57:00 volumio.dlna sudo[13996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-12 06:56 Mar 12 06:57:00 volumio.dlna sudo[13996]: 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="7434a810e3a57a07e8ee53a8f2063b5b15d2425c" VOLUMIO_FE_VERSION="8d42aa2ed20c7c37b42519af63b83b0cbcd3af68" VOLUMIO_BE_VERSION="fd4f91711c718295fa83ff62d1b2772d1cb50601" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Sep 3 19:53:55 CEST 2019" VOLUMIO_VERSION="2.603" VOLUMIO_HARDWARE="vim1" VOLUMIO_HASH="b2272113b215eebd4e2ea68b15bca646"