-- Logs begin at Tue 2024-10-22 22:00:19 CEST, end at Wed 2024-10-23 08:48:57 CEST. -- Oct 23 08:47:00 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:47:05 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:47:08 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:47:12 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:47:16 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:47:20 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:47:25 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:47:28 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:47:31 volumio-hanciba volumio[846]: An internal error occurred while serving an albumart. Details: Error: read ETIMEDOUT Oct 23 08:47:31 volumio-hanciba volumio[846]: at TCP.onStreamRead (internal/stream_base_commons.js:209:20) Oct 23 08:47:31 volumio-hanciba volumio[846]: Error: Can't set headers after they are sent. Oct 23 08:47:31 volumio-hanciba volumio[846]: at SendStream.headersAlreadySent (/volumio/node_modules/send/index.js:390:13) Oct 23 08:47:31 volumio-hanciba volumio[846]: at SendStream.send (/volumio/node_modules/send/index.js:617:10) Oct 23 08:47:31 volumio-hanciba volumio[846]: at onstat (/volumio/node_modules/send/index.js:729:10) Oct 23 08:47:31 volumio-hanciba volumio[846]: at FSReqCallback.oncomplete (fs.js:193:5) Oct 23 08:47:32 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:47:36 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:47:40 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:47:45 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:47:48 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:47:52 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:47:56 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:48:00 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:48:02 volumio-hanciba volumio[846]: info: CorePlayQueue::getTrack 1 Oct 23 08:48:02 volumio-hanciba volumio[846]: info: CorePlayQueue::getTrack 2 Oct 23 08:48:02 volumio-hanciba volumio[846]: info: Prefetching next song Oct 23 08:48:02 volumio-hanciba volumio[846]: info: DOING PREFETCH IN MPD Oct 23 08:48:02 volumio-hanciba volumio[846]: verbose: ControllerMpd::sendMpdCommand add "USB/Volumio-Hanciba/TR24-3/Brad Turner - The Magnificent (2023) [24-96]/03 - Slapped My Mind.flac" Oct 23 08:48:02 volumio-hanciba volumio[846]: info: Oct 23 08:48:02 volumio-hanciba volumio[846]: ---------------------------- MPD announces system playlist update Oct 23 08:48:02 volumio-hanciba volumio[846]: info: Ignoring MPD Status Update Oct 23 08:48:02 volumio-hanciba volumio[846]: info: sendMpdCommand add "USB/Volumio-Hanciba/TR24-3/Brad Turner - The Magnificent (2023) [24-96]/03 - Slapped My Mind.flac" took 1 milliseconds Oct 23 08:48:02 volumio-hanciba volumio[846]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 23 08:48:02 volumio-hanciba volumio[846]: info: ------------------------------ 2ms Oct 23 08:48:02 volumio-hanciba volumio[846]: info: sendMpdCommand consume 1 took 2 milliseconds Oct 23 08:48:03 volumio-hanciba volumio[846]: info: Tunnel connection is inactive, restarting it Oct 23 08:48:03 volumio-hanciba volumio[846]: info: Starting Tunnel 1 Oct 23 08:48:03 volumio-hanciba volumio[846]: info: Starting Tunnel Connection Checker Oct 23 08:48:03 volumio-hanciba sudo[1402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 23 08:48:03 volumio-hanciba sudo[1402]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 08:48:03 volumio-hanciba autossh[1248]: received signal to exit (15) Oct 23 08:48:03 volumio-hanciba systemd[1]: Stopping MyVolumio SSH Tunnel... Oct 23 08:48:03 volumio-hanciba systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Oct 23 08:48:03 volumio-hanciba systemd[1]: sshtunnel.service: Succeeded. Oct 23 08:48:03 volumio-hanciba systemd[1]: Stopped MyVolumio SSH Tunnel. Oct 23 08:48:03 volumio-hanciba systemd[1]: Started MyVolumio SSH Tunnel. Oct 23 08:48:03 volumio-hanciba sudo[1402]: pam_unix(sudo:session): session closed for user root Oct 23 08:48:03 volumio-hanciba autossh[1411]: port set to 0, monitoring disabled Oct 23 08:48:03 volumio-hanciba autossh[1411]: starting ssh (count 1) Oct 23 08:48:03 volumio-hanciba autossh[1411]: ssh child pid is 1415 Oct 23 08:48:03 volumio-hanciba volumio[846]: info: Remote SSH Started Oct 23 08:48:03 volumio-hanciba volumio[846]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 23 08:48:03 volumio-hanciba volumio[846]: info: CoreCommandRouter::volumioGetState Oct 23 08:48:03 volumio-hanciba volumio[846]: info: CorePlayQueue::getTrack 1 Oct 23 08:48:05 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:48:06 volumio-hanciba volumio[846]: info: Oct 23 08:48:06 volumio-hanciba volumio[846]: ---------------------------- MPD announces system playlist update Oct 23 08:48:06 volumio-hanciba volumio[846]: info: Ignoring MPD Status Update Oct 23 08:48:06 volumio-hanciba volumio[846]: info: Oct 23 08:48:06 volumio-hanciba volumio[846]: ---------------------------- MPD announces state update: player Oct 23 08:48:06 volumio-hanciba volumio[846]: info: ControllerMpd::getState Oct 23 08:48:06 volumio-hanciba volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 23 08:48:06 volumio-hanciba volumio[846]: info: ------------------------------ 2ms Oct 23 08:48:06 volumio-hanciba volumio[846]: info: sendMpdCommand status took 1 milliseconds Oct 23 08:48:06 volumio-hanciba volumio[846]: verbose: ControllerMpd::parseState Oct 23 08:48:06 volumio-hanciba volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 23 08:48:06 volumio-hanciba volumio[846]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 23 08:48:06 volumio-hanciba volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 23 08:48:06 volumio-hanciba volumio[846]: info: ControllerMpd::pushState Oct 23 08:48:06 volumio-hanciba volumio[846]: info: CoreCommandRouter::servicePushState Oct 23 08:48:06 volumio-hanciba volumio[846]: info: CorePlayQueue::getTrack 1 Oct 23 08:48:06 volumio-hanciba volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":465,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2874 Kbps","isStreaming":false,"title":"Slapped My Mind","artist":"Brad Turner","album":"The Magnificent","uri":"USB/Volumio-Hanciba/TR24-3/Brad Turner - The Magnificent (2023) [24-96]/03 - Slapped My Mind.flac","trackType":"flac"} Oct 23 08:48:06 volumio-hanciba volumio[846]: verbose: CURRENT POSITION 1 Oct 23 08:48:06 volumio-hanciba volumio[846]: info: CoreStateMachine::syncState stateService play Oct 23 08:48:06 volumio-hanciba volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 23 08:48:06 volumio-hanciba volumio[846]: info: Received an update from plugin. extracting info from payload Oct 23 08:48:06 volumio-hanciba volumio[846]: info: CoreStateMachine::pushState Oct 23 08:48:06 volumio-hanciba volumio[846]: info: CorePlayQueue::getTrack 1 Oct 23 08:48:06 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 08:48:06 volumio-hanciba volumio[846]: info: CoreCommandRouter::volumioPushState Oct 23 08:48:06 volumio-hanciba volumio[846]: info: CoreStateMachine::pushState Oct 23 08:48:06 volumio-hanciba volumio[846]: info: CorePlayQueue::getTrack 1 Oct 23 08:48:06 volumio-hanciba volumio[846]: info: CoreCommandRouter::volumioPushState Oct 23 08:48:06 volumio-hanciba volumio[846]: info: ------------------------------ 9ms Oct 23 08:48:07 volumio-hanciba volumio[846]: info: CoreStateMachine::startPlaybackTimer Oct 23 08:48:07 volumio-hanciba volumio[846]: info: CorePlayQueue::getTrack 2 Oct 23 08:48:07 volumio-hanciba volumio[846]: info: CoreStateMachine::pushState Oct 23 08:48:07 volumio-hanciba volumio[846]: info: CorePlayQueue::getTrack 2 Oct 23 08:48:07 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 08:48:07 volumio-hanciba volumio[846]: info: CoreCommandRouter::volumioPushState Oct 23 08:48:08 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:48:12 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:48:16 volumio-hanciba volumiossh-tunnel[1408]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Oct 23 08:48:16 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:48:20 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:48:25 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:48:28 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:48:31 volumio-hanciba volumio[846]: info: Disabling MyMusic plugin upnp_browser Oct 23 08:48:31 volumio-hanciba volumio[846]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMédiaszerverek Oct 23 08:48:31 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 23 08:48:31 volumio-hanciba volumio[846]: Cannot find translation for source TIDAL Oct 23 08:48:31 volumio-hanciba volumio[846]: info: Disabling plugin upnp_browser Oct 23 08:48:31 volumio-hanciba volumio[846]: info: Done. Oct 23 08:48:32 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:48:36 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:48:39 volumio-hanciba volumio[846]: info: Disabling MyMusic plugin airplay_emulation Oct 23 08:48:39 volumio-hanciba volumio[846]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync Oct 23 08:48:39 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 23 08:48:39 volumio-hanciba volumio[846]: Cannot find translation for source TIDAL Oct 23 08:48:39 volumio-hanciba volumio[846]: info: Disabling plugin airplay_emulation Oct 23 08:48:39 volumio-hanciba volumio[846]: info: Done. Oct 23 08:48:39 volumio-hanciba sudo[1420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Oct 23 08:48:39 volumio-hanciba sudo[1420]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 08:48:39 volumio-hanciba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 23 08:48:39 volumio-hanciba systemd[1]: shairport-sync.service: Succeeded. Oct 23 08:48:39 volumio-hanciba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 23 08:48:39 volumio-hanciba sudo[1420]: pam_unix(sudo:session): session closed for user root Oct 23 08:48:39 volumio-hanciba volumio[846]: info: Shairport-Sync Stopped Oct 23 08:48:40 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:48:45 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:48:48 volumio-hanciba volumio[846]: info: Disabling MyMusic plugin upnp Oct 23 08:48:48 volumio-hanciba sudo[1431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 23 08:48:48 volumio-hanciba sudo[1431]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 08:48:48 volumio-hanciba systemd[1]: Stopping UPnP Renderer front-end to MPD... Oct 23 08:48:48 volumio-hanciba volumio[846]: error: Upnp client error: Error: This socket has been ended by the other party Oct 23 08:48:48 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:48:52 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:48:56 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 23 08:48:57 volumio-hanciba volumio[846]: info: Enabling MyMusic plugin upnp Oct 23 08:48:57 volumio-hanciba volumio[846]: info: Enabling plugin upnp Oct 23 08:48:57 volumio-hanciba volumio[846]: info: Loading plugin "upnp"... Oct 23 08:48:57 volumio-hanciba volumio[846]: info: [1729666137427] Starting Upmpd Daemon Oct 23 08:48:57 volumio-hanciba volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 23 08:48:57 volumio-hanciba volumio[846]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 23 08:48:57 volumio-hanciba volumio[846]: Error: listen EADDRINUSE: address already in use :::6599 Oct 23 08:48:57 volumio-hanciba volumio[846]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Oct 23 08:48:57 volumio-hanciba volumio[846]: at listenInCluster (net.js:1379:12) Oct 23 08:48:57 volumio-hanciba volumio[846]: at Server.listen (net.js:1465:7) Oct 23 08:48:57 volumio-hanciba volumio[846]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 23 08:48:57 volumio-hanciba volumio[846]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 23 08:48:57 volumio-hanciba volumio[846]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 23 08:48:57 volumio-hanciba volumio[846]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 23 08:48:57 volumio-hanciba volumio[846]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Oct 23 08:48:57 volumio-hanciba volumio[846]: code: 'EADDRINUSE', Oct 23 08:48:57 volumio-hanciba volumio[846]: errno: -98, Oct 23 08:48:57 volumio-hanciba volumio[846]: syscall: 'listen', Oct 23 08:48:57 volumio-hanciba volumio[846]: address: '::', Oct 23 08:48:57 volumio-hanciba volumio[846]: port: 6599 Oct 23 08:48:57 volumio-hanciba volumio[846]: } Oct 23 08:48:57 volumio-hanciba volumio[846]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 23 08:48:57 volumio-hanciba sudo[1446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-23 08:47 Oct 23 08:48:57 volumio-hanciba sudo[1446]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:19:36 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="43b87fbef9c19ede18711bdf23c439c5"