-- Logs begin at Thu 2019-02-14 17:11:59 +07, end at Wed 2024-05-08 12:46:01 +07. -- May 08 12:45:03 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 12:45:07 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 12:45:11 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 12:45:12 volumio volumio[740]: info: Enabling MyMusic plugin tidalconnect May 08 12:45:12 volumio volumio[740]: info: [MyVolumio PluginManager] Enabling and starting plugin music_service tidalconnect May 08 12:45:12 volumio volumio[740]: info: [MyVolumio PluginManager] Plugin music_service tidalconnect not in user plan, not enabling May 08 12:45:12 volumio volumio[740]: info: Error: Plugin music_service tidalconnect not in user plan, not enabling May 08 12:45:12 volumio volumio[740]: error: Could not Enable MyMusic Plugin: Error May 08 12:45:15 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 12:45:18 volumio volumio[740]: info: Disabling MyMusic plugin upnp_browser May 08 12:45:18 volumio volumio[740]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMáy chủ Media May 08 12:45:18 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 12:45:18 volumio volumio[740]: Cannot find translation for source YouTube2 May 08 12:45:18 volumio volumio[740]: Cannot find translation for source Spotify May 08 12:45:18 volumio volumio[740]: info: Disabling plugin upnp_browser May 08 12:45:18 volumio volumio[740]: info: Done. May 08 12:45:19 volumio volumio[740]: info: Enabling MyMusic plugin upnp_browser May 08 12:45:19 volumio volumio[740]: info: Enabling plugin upnp_browser May 08 12:45:19 volumio volumio[740]: info: Loading plugin "upnp_browser"... May 08 12:45:19 volumio volumio[740]: info: PLUGIN START: upnp_browser May 08 12:45:19 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 12:45:19 volumio volumio[740]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 08 12:45:19 volumio volumio[740]: info: [1715147119782] CoreMusicLibrary::Adding element Máy chủ Media May 08 12:45:19 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 12:45:19 volumio volumio[740]: Cannot find translation for source YouTube2 May 08 12:45:19 volumio volumio[740]: Cannot find translation for source Spotify May 08 12:45:19 volumio volumio[740]: info: Done. May 08 12:45:19 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 12:45:23 volumio volumio[740]: info: Disabling MyMusic plugin upnp May 08 12:45:23 volumio sudo[4022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service May 08 12:45:23 volumio sudo[4022]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 12:45:23 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... May 08 12:45:24 volumio volumio[740]: error: Upnp client error: Error: This socket has been ended by the other party May 08 12:45:24 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 12:45:24 volumio volumio[740]: info: Disabling MyMusic plugin airplay_emulation May 08 12:45:24 volumio volumio[740]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync May 08 12:45:24 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 12:45:24 volumio volumio[740]: Cannot find translation for source YouTube2 May 08 12:45:24 volumio volumio[740]: Cannot find translation for source Spotify May 08 12:45:24 volumio volumio[740]: info: Disabling plugin airplay_emulation May 08 12:45:24 volumio volumio[740]: info: Done. May 08 12:45:24 volumio sudo[4025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync May 08 12:45:24 volumio sudo[4025]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 12:45:24 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 08 12:45:24 volumio systemd[1]: shairport-sync.service: Succeeded. May 08 12:45:24 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 08 12:45:24 volumio sudo[4025]: pam_unix(sudo:session): session closed for user root May 08 12:45:24 volumio volumio[740]: info: Shairport-Sync Stopped May 08 12:45:26 volumio volumio[740]: info: Disabling MyMusic plugin upnp_browser May 08 12:45:26 volumio volumio[740]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMáy chủ Media May 08 12:45:26 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 12:45:26 volumio volumio[740]: Cannot find translation for source YouTube2 May 08 12:45:26 volumio volumio[740]: Cannot find translation for source Spotify May 08 12:45:26 volumio volumio[740]: info: Disabling plugin upnp_browser May 08 12:45:26 volumio volumio[740]: info: Done. May 08 12:45:27 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 12:45:30 volumio volumio[740]: info: Disabling MyMusic plugin upnp May 08 12:45:30 volumio sudo[4028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service May 08 12:45:30 volumio sudo[4028]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 12:45:31 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 12:45:35 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 12:45:38 volumio volumio[740]: info: CoreCommandRouter::volumioPlay May 08 12:45:38 volumio volumio[740]: info: CoreStateMachine::play index undefined May 08 12:45:38 volumio volumio[740]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 12:45:38 volumio volumio[740]: info: CorePlayQueue::getTrack 1 May 08 12:45:38 volumio volumio[740]: info: CoreStateMachine::startPlaybackTimer May 08 12:45:38 volumio volumio[740]: info: CorePlayQueue::getTrack 1 May 08 12:45:38 volumio volumio[740]: info: [1715147138484] ControllerSpotify::clearAddPlayTrack May 08 12:45:38 volumio volumio[740]: info: Sending Spotify command with payload to local API: /player/play May 08 12:45:38 volumio go-librespot[3309]: time="2024-05-08T12:45:38+07:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" May 08 12:45:38 volumio go-librespot[3309]: time="2024-05-08T12:45:38+07:00" level=debug msg="loading track spotify:track:6bw6X6QHcyNaw4lKApx3ii (paused: false, position: 1ms)" May 08 12:45:38 volumio go-librespot[3309]: time="2024-05-08T12:45:38+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 08 12:45:38 volumio go-librespot[3309]: time="2024-05-08T12:45:38+07:00" level=trace msg="emitting websocket event: will_play" May 08 12:45:38 volumio volumio[740]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6bw6X6QHcyNaw4lKApx3ii","play_origin":"go-librespot"}} May 08 12:45:38 volumio volumio[740]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6bw6X6QHcyNaw4lKApx3ii","play_origin":"go-librespot"}} May 08 12:45:38 volumio volumio[740]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6bw6X6QHcyNaw4lKApx3ii","play_origin":"go-librespot"}} May 08 12:45:38 volumio go-librespot[3309]: time="2024-05-08T12:45:38+07:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:6bw6X6QHcyNaw4lKApx3ii" May 08 12:45:38 volumio go-librespot[3309]: time="2024-05-08T12:45:38+07:00" level=debug msg="requested aes key for file 784c0ace0edbbe6daf8c6f7cb179f5633db585cd, gid: 6bw6X6QHcyNaw4lKApx3ii" May 08 12:45:38 volumio go-librespot[3309]: time="2024-05-08T12:45:38+07:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: failed retrieving audio key: failed retrieving aes key with code 1" May 08 12:45:38 volumio volumio[740]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error May 08 12:45:39 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 12:45:43 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares May 08 12:45:43 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService May 08 12:45:43 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 12:45:45 volumio ntpd[575]: Soliciting pool server 2401:c080:3000:2945:5400:4ff:fe69:f923 May 08 12:45:47 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 12:45:51 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 12:45:53 volumio volumio[740]: info: Enabling MyMusic plugin airplay_emulation May 08 12:45:53 volumio volumio[740]: info: Enabling plugin airplay_emulation May 08 12:45:53 volumio volumio[740]: info: Loading plugin "airplay_emulation"... May 08 12:45:53 volumio volumio[740]: info: Starting Shairport Sync May 08 12:45:53 volumio volumio[740]: info: PLUGIN START: airplay_emulation May 08 12:45:53 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 12:45:53 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 12:45:53 volumio volumio[740]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 08 12:45:53 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 12:45:53 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 12:45:53 volumio volumio[740]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 08 12:45:53 volumio volumio[740]: info: Done. May 08 12:45:53 volumio volumio[740]: info: Starting Shairport Sync May 08 12:45:53 volumio volumio[740]: info: Starting Shairport Sync May 08 12:45:53 volumio sudo[4078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 08 12:45:53 volumio sudo[4078]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 12:45:53 volumio sudo[4081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 08 12:45:53 volumio sudo[4081]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 12:45:53 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 08 12:45:53 volumio sudo[4078]: pam_unix(sudo:session): session closed for user root May 08 12:45:53 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 08 12:45:53 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 08 12:45:53 volumio systemd[1]: shairport-sync.service: Succeeded. May 08 12:45:53 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 08 12:45:53 volumio volumio[740]: info: Shairport-Sync Started May 08 12:45:53 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 08 12:45:53 volumio sudo[4081]: pam_unix(sudo:session): session closed for user root May 08 12:45:53 volumio volumio[740]: info: Shairport-Sync Started May 08 12:45:55 volumio volumio[740]: info: Disabling MyMusic plugin airplay_emulation May 08 12:45:55 volumio volumio[740]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync May 08 12:45:55 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 12:45:55 volumio volumio[740]: Cannot find translation for source YouTube2 May 08 12:45:55 volumio volumio[740]: Cannot find translation for source Spotify May 08 12:45:55 volumio volumio[740]: info: Disabling plugin airplay_emulation May 08 12:45:55 volumio volumio[740]: info: Done. May 08 12:45:55 volumio sudo[4089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync May 08 12:45:55 volumio sudo[4089]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 12:45:55 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 08 12:45:55 volumio systemd[1]: shairport-sync.service: Succeeded. May 08 12:45:55 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 08 12:45:55 volumio sudo[4089]: pam_unix(sudo:session): session closed for user root May 08 12:45:55 volumio volumio[740]: info: Shairport-Sync Stopped May 08 12:45:55 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 12:45:56 volumio volumio[740]: info: Enabling MyMusic plugin airplay_emulation May 08 12:45:56 volumio volumio[740]: info: Enabling plugin airplay_emulation May 08 12:45:56 volumio volumio[740]: info: Loading plugin "airplay_emulation"... May 08 12:45:56 volumio volumio[740]: info: Starting Shairport Sync May 08 12:45:56 volumio volumio[740]: info: PLUGIN START: airplay_emulation May 08 12:45:56 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 12:45:56 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 12:45:56 volumio volumio[740]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 08 12:45:56 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 12:45:56 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 12:45:56 volumio volumio[740]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 08 12:45:56 volumio volumio[740]: info: Done. May 08 12:45:56 volumio volumio[740]: info: Starting Shairport Sync May 08 12:45:56 volumio volumio[740]: info: Starting Shairport Sync May 08 12:45:56 volumio sudo[4099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 08 12:45:56 volumio sudo[4099]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 12:45:56 volumio sudo[4102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 08 12:45:56 volumio sudo[4102]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 12:45:56 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 08 12:45:56 volumio sudo[4099]: pam_unix(sudo:session): session closed for user root May 08 12:45:56 volumio volumio[740]: info: Shairport-Sync Started May 08 12:45:56 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 08 12:45:56 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 08 12:45:56 volumio systemd[1]: shairport-sync.service: Succeeded. May 08 12:45:56 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 08 12:45:56 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 08 12:45:56 volumio sudo[4102]: pam_unix(sudo:session): session closed for user root May 08 12:45:56 volumio volumio[740]: info: Shairport-Sync Started May 08 12:45:59 volumio volumio[740]: info: Disabling MyMusic plugin upnp May 08 12:45:59 volumio sudo[4109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service May 08 12:45:59 volumio sudo[4109]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 12:45:59 volumio volumio[740]: info: Enabling MyMusic plugin upnp May 08 12:45:59 volumio volumio[740]: info: Enabling plugin upnp May 08 12:45:59 volumio volumio[740]: info: Loading plugin "upnp"... May 08 12:45:59 volumio volumio[740]: info: [1715147159933] Starting Upmpd Daemon May 08 12:45:59 volumio volumio[740]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 08 12:45:59 volumio volumio[740]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 12:46:00 volumio volumio[740]: Error: listen EADDRINUSE: address already in use :::6599 May 08 12:46:00 volumio volumio[740]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) May 08 12:46:00 volumio volumio[740]: at listenInCluster (net.js:1366:12) May 08 12:46:00 volumio volumio[740]: at Server.listen (net.js:1452:7) May 08 12:46:00 volumio volumio[740]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) May 08 12:46:00 volumio volumio[740]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) May 08 12:46:00 volumio volumio[740]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) May 08 12:46:00 volumio volumio[740]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 08 12:46:00 volumio volumio[740]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { May 08 12:46:00 volumio volumio[740]: code: 'EADDRINUSE', May 08 12:46:00 volumio volumio[740]: errno: -98, May 08 12:46:00 volumio volumio[740]: syscall: 'listen', May 08 12:46:00 volumio volumio[740]: address: '::', May 08 12:46:00 volumio volumio[740]: port: 6599 May 08 12:46:00 volumio volumio[740]: } May 08 12:46:00 volumio volumio[740]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 12:46:01 volumio sudo[4122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-08 12:45 May 08 12:46:01 volumio sudo[4122]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"