-- Logs begin at Thu 2024-10-10 07:30:16 UTC, end at Thu 2024-10-10 08:48:05 UTC. -- Oct 10 08:47:04 volumio_hd2 volumio[1048]: info: CoreStateMachine::startPlaybackTimer Oct 10 08:47:04 volumio_hd2 volumio[1048]: info: CorePlayQueue::getTrack 4 Oct 10 08:47:04 volumio_hd2 volumio[1048]: info: Oct 10 08:47:04 volumio_hd2 volumio[1048]: ---------------------------- MPD announces system playlist update Oct 10 08:47:04 volumio_hd2 volumio[1048]: info: Ignoring MPD Status Update Oct 10 08:47:04 volumio_hd2 volumio[1048]: info: Oct 10 08:47:04 volumio_hd2 volumio[1048]: ---------------------------- MPD announces state update: player Oct 10 08:47:04 volumio_hd2 volumio[1048]: info: ControllerMpd::getState Oct 10 08:47:04 volumio_hd2 volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Oct 10 08:47:04 volumio_hd2 volumio[1048]: info: ------------------------------ 3ms Oct 10 08:47:04 volumio_hd2 volumio[1048]: info: sendMpdCommand status took 1 milliseconds Oct 10 08:47:04 volumio_hd2 volumio[1048]: verbose: ControllerMpd::parseState Oct 10 08:47:04 volumio_hd2 volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 08:47:04 volumio_hd2 volumio[1048]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 10 08:47:04 volumio_hd2 volumio[1048]: verbose: ControllerMpd::parseTrackInfo Oct 10 08:47:04 volumio_hd2 volumio[1048]: info: ControllerMpd::pushState Oct 10 08:47:04 volumio_hd2 volumio[1048]: info: CoreCommandRouter::servicePushState Oct 10 08:47:04 volumio_hd2 volumio[1048]: info: CorePlayQueue::getTrack 4 Oct 10 08:47:04 volumio_hd2 volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":23,"duration":169,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"393 Kbps","isStreaming":false,"title":"The Crossing","artist":"Lisa Gerrard","album":"The Black Opal","uri":"USB/Extreme SSD/MusiqueATrier/Lisa Gerrard/The Black Opal/05 - The Crossing.flac","trackType":"flac"} Oct 10 08:47:04 volumio_hd2 volumio[1048]: verbose: CURRENT POSITION 4 Oct 10 08:47:04 volumio_hd2 volumio[1048]: info: CoreStateMachine::syncState stateService play Oct 10 08:47:04 volumio_hd2 volumio[1048]: info: CoreStateMachine::syncState currentStatus play Oct 10 08:47:04 volumio_hd2 volumio[1048]: info: Received an update from plugin. extracting info from payload Oct 10 08:47:04 volumio_hd2 volumio[1048]: info: CoreStateMachine::pushState Oct 10 08:47:04 volumio_hd2 volumio[1048]: info: CorePlayQueue::getTrack 4 Oct 10 08:47:04 volumio_hd2 volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 10 08:47:04 volumio_hd2 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 08:47:04 volumio_hd2 volumio[1048]: info: CoreStateMachine::pushState Oct 10 08:47:04 volumio_hd2 volumio[1048]: info: CorePlayQueue::getTrack 4 Oct 10 08:47:04 volumio_hd2 volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 10 08:47:04 volumio_hd2 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 08:47:04 volumio_hd2 volumio[1048]: info: ------------------------------ 34ms Oct 10 08:47:39 volumio_hd2 sudo[9174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 10 08:47:39 volumio_hd2 sudo[9174]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 08:47:39 volumio_hd2 sudo[9174]: pam_unix(sudo:session): session closed for user root Oct 10 08:47:39 volumio_hd2 sudo[9177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 10 08:47:39 volumio_hd2 sudo[9177]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 08:47:39 volumio_hd2 sudo[9177]: pam_unix(sudo:session): session closed for user root Oct 10 08:47:40 volumio_hd2 sudo[9194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 10 08:47:40 volumio_hd2 sudo[9194]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 08:47:40 volumio_hd2 sudo[9194]: pam_unix(sudo:session): session closed for user root Oct 10 08:47:40 volumio_hd2 sudo[9198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 10 08:47:40 volumio_hd2 sudo[9198]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 08:47:40 volumio_hd2 sudo[9198]: pam_unix(sudo:session): session closed for user root Oct 10 08:47:40 volumio_hd2 volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 10 08:47:40 volumio_hd2 volumio[1048]: info: CorePlayQueue::getTrack 4 Oct 10 08:47:40 volumio_hd2 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 10 08:47:40 volumio_hd2 volumio[1048]: info: Listing playlists Oct 10 08:47:40 volumio_hd2 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 10 08:47:40 volumio_hd2 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 10 08:47:40 volumio_hd2 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 10 08:47:40 volumio_hd2 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 10 08:47:42 volumio_hd2 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 10 08:47:42 volumio_hd2 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 10 08:47:44 volumio_hd2 volumio[1048]: info: Tunnel connection is inactive, restarting it Oct 10 08:47:44 volumio_hd2 volumio[1048]: info: Starting Tunnel 1 Oct 10 08:47:44 volumio_hd2 volumio[1048]: info: Starting Tunnel Connection Checker Oct 10 08:47:44 volumio_hd2 sudo[9229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 10 08:47:44 volumio_hd2 sudo[9229]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 08:47:44 volumio_hd2 systemd[1]: Stopping MyVolumio SSH Tunnel... Oct 10 08:47:44 volumio_hd2 autossh[8753]: received signal to exit (15) Oct 10 08:47:44 volumio_hd2 systemd[1]: Starting MyVolumio SSH Tunnel... Oct 10 08:47:44 volumio_hd2 systemd[1]: Started MyVolumio SSH Tunnel. Oct 10 08:47:44 volumio_hd2 sudo[9229]: pam_unix(sudo:session): session closed for user root Oct 10 08:47:44 volumio_hd2 autossh[9237]: port set to 0, monitoring disabled Oct 10 08:47:44 volumio_hd2 autossh[9237]: starting ssh (count 1) Oct 10 08:47:44 volumio_hd2 autossh[9237]: ssh child pid is 9242 Oct 10 08:47:44 volumio_hd2 volumio[1048]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 10 08:47:44 volumio_hd2 volumio[1048]: BT PLUGIN MESSAGE: Playing: false Oct 10 08:47:44 volumio_hd2 volumio[1048]: BT PLUGIN MESSAGE: VOLATILE: undefined Oct 10 08:47:44 volumio_hd2 volumio[1048]: BT PLUGIN MESSAGE: STATE:undefined Oct 10 08:47:44 volumio_hd2 volumio[1048]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 10 08:47:44 volumio_hd2 volumio[1048]: BT PLUGIN MESSAGE: Playing: false Oct 10 08:47:44 volumio_hd2 volumio[1048]: BT PLUGIN MESSAGE: VOLATILE: undefined Oct 10 08:47:44 volumio_hd2 volumio[1048]: BT PLUGIN MESSAGE: STATE:undefined Oct 10 08:47:44 volumio_hd2 volumio[1048]: info: Remote SSH Started Oct 10 08:47:44 volumio_hd2 volumio[1048]: info: CoreCommandRouter::volumioGetState Oct 10 08:47:44 volumio_hd2 volumio[1048]: info: CorePlayQueue::getTrack 4 Oct 10 08:47:44 volumio_hd2 volumiossh-tunnel[9235]: Warning: Permanently added '[eu6.myvolumio.org]:2222,[207.154.233.230]:2222' (RSA) to the list of known hosts. Oct 10 08:47:44 volumio_hd2 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 10 08:47:44 volumio_hd2 volumio[1048]: info: CoreCommandRouter::volumioGetVisibleSources Oct 10 08:47:44 volumio_hd2 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 08:47:44 volumio_hd2 volumio[1048]: info: CoreCommandRouter::volumioGetQueue Oct 10 08:47:44 volumio_hd2 volumio[1048]: info: CoreStateMachine::getQueue Oct 10 08:47:44 volumio_hd2 volumio[1048]: info: CorePlayQueue::getQueue Oct 10 08:47:46 volumio_hd2 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 10 08:47:46 volumio_hd2 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 10 08:47:46 volumio_hd2 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 10 08:47:46 volumio_hd2 volumio[1048]: info: CURURI: music-library Oct 10 08:47:47 volumio_hd2 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 10 08:47:47 volumio_hd2 volumio[1048]: info: CURURI: music-library/USB Oct 10 08:47:48 volumio_hd2 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 10 08:47:48 volumio_hd2 volumio[1048]: info: CURURI: music-library/USB/Extreme SSD Oct 10 08:47:49 volumio_hd2 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 10 08:47:49 volumio_hd2 volumio[1048]: info: CURURI: music-library/USB/Extreme SSD/MusiqueATrier Oct 10 08:47:56 volumio_hd2 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 10 08:47:56 volumio_hd2 volumio[1048]: info: CURURI: music-library/USB/Extreme SSD/MusiqueATrier/Georg Philipp Telemann - Viola Concertos - Overtu... (902342DI FLAC 24-bit).zip Oct 10 08:47:59 volumio_hd2 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 10 08:47:59 volumio_hd2 volumio[1048]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 10 08:47:59 volumio_hd2 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 10 08:47:59 volumio_hd2 volumio[1048]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Oct 10 08:47:59 volumio_hd2 volumio[1048]: info: Received Get System Version Oct 10 08:47:59 volumio_hd2 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: CoreStateMachine::ClearQueue Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: CoreStateMachine::stop Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 10 08:48:04 volumio_hd2 volumio[1048]: verbose: UNSET VOLATILE Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: CoreStateMachine::stPlaybackTimer Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: CoreStateMachine::updateTrackBlock Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: CorePlayQueue::getTrackBlock Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: CoreStateMachine::pushState Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: CorePlayQueue::getTrack 4 Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: CoreStateMachine::serviceStop Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: CorePlayQueue::getTrack 4 Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: CoreCommandRouter::serviceStop Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: ControllerMpd::stop Oct 10 08:48:04 volumio_hd2 volumio[1048]: verbose: ControllerMpd::sendMpdCommand stop Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: CorePlayQueue::clearPlayQueue Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: CorePlayQueue::saveQueue Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: CoreCommandRouter::volumioPushState Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: CoreCommandRouter::volumioPushQueue Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: CoreStateMachine::addQueueItems Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: CorePlayQueue::addQueueItems Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: Adding Item to queue: music-library/USB/Extreme SSD/MusiqueATrier/Georg Philipp Telemann - Viola Concertos - Overtu... (902342DI FLAC 24-bit).zip Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: Exploding uri music-library/USB/Extreme SSD/MusiqueATrier/Georg Philipp Telemann - Viola Concertos - Overtu... (902342DI FLAC 24-bit).zip in service mpd Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: Oct 10 08:48:04 volumio_hd2 volumio[1048]: ---------------------------- MPD announces state update: player Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: ALBUMART /albumart?cacheid=74&web=Tamestit%2C%20Antoine/Georg%20Philipp%20Telemann%3A%20Viola%20Concertos%20-%20Overtures%20-%20Fantasias/extralarge&path=%2Fmnt%2FUSB%2FExtreme%20SSD%2FMusiqueATrier%2FGeorg%20Philipp%20Telemann%20-%20Viola%20Concertos%20-%20Overtu...%20(902342DI%20FLAC%2024-bit).zip&metadata=false Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: URI /mnt/USB/Extreme SSD/MusiqueATrier/Georg Philipp Telemann - Viola Concertos - Overtu... (902342DI FLAC 24-bit).zip Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: ALBUMART /albumart?cacheid=74&web=Tamestit%2C%20Antoine/Georg%20Philipp%20Telemann%3A%20Viola%20Concertos%20-%20Overtures%20-%20Fantasias/extralarge&path=%2Fmnt%2FUSB%2FExtreme%20SSD%2FMusiqueATrier%2FGeorg%20Philipp%20Telemann%20-%20Viola%20Concertos%20-%20Overtu...%20(902342DI%20FLAC%2024-bit).zip&metadata=false Oct 10 08:48:04 volumio_hd2 volumio[1048]: info: URI /mnt/USB/Extreme SSD/MusiqueATrier/Georg Philipp Telemann - Viola Concertos - Overtu... (902342DI FLAC 24-bit).zip Oct 10 08:48:04 volumio_hd2 volumio[1048]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 10 08:48:04 volumio_hd2 volumio[1048]: Error: Unable to resolve or reject the same promise twice Oct 10 08:48:04 volumio_hd2 volumio[1048]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 10 08:48:04 volumio_hd2 volumio[1048]: at /volumio/app/plugins/music_service/mpd/index.js:2520:21 Oct 10 08:48:04 volumio_hd2 volumio[1048]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 10 08:48:04 volumio_hd2 volumio[1048]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 10 08:48:04 volumio_hd2 volumio[1048]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 10 08:48:04 volumio_hd2 volumio[1048]: at emitOne (events.js:116:13) Oct 10 08:48:04 volumio_hd2 volumio[1048]: at Socket.emit (events.js:211:7) Oct 10 08:48:04 volumio_hd2 volumio[1048]: at addChunk (_stream_readable.js:263:12) Oct 10 08:48:04 volumio_hd2 volumio[1048]: at readableAddChunk (_stream_readable.js:246:13) Oct 10 08:48:04 volumio_hd2 volumio[1048]: at Socket.Readable.push (_stream_readable.js:208:10) Oct 10 08:48:04 volumio_hd2 volumio[1048]: at Pipe.onread (net.js:607:20) Oct 10 08:48:04 volumio_hd2 volumio[1048]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 10 08:48:05 volumio_hd2 sudo[9314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-10 08:47 Oct 10 08:48:05 volumio_hd2 sudo[9314]: 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"