-- Logs begin at Thu 2019-02-14 04:11:59 CST, end at Wed 2024-10-30 08:26:55 CDT. -- Oct 30 08:25:25 juke-box-hero ntpd[697]: receive: Unexpected origin timestamp 0xe7c0074a.d84981f5 does not match aorg 0000000000.00000000 from server@155.248.196.28 xmt 0xeaccb245.395454d3 Oct 30 08:25:25 juke-box-hero systemd[1]: Starting Daily man-db regeneration... Oct 30 08:25:25 juke-box-hero systemd[1]: Starting Daily apt download activities... Oct 30 08:25:25 juke-box-hero volumio[958]: info: Discovery: A device disappeared from network Oct 30 08:25:25 juke-box-hero volumio[958]: info: Discovery: adding c7eb338f-add1-4404-93e8-589a2bc0305b Oct 30 08:25:25 juke-box-hero volumio[958]: info: Discovery: Found device Juke Box Hero Oct 30 08:25:25 juke-box-hero volumio[958]: info: CoreCommandRouter::volumioGetState Oct 30 08:25:25 juke-box-hero volumio[958]: info: CorePlayQueue::getTrack 0 Oct 30 08:25:25 juke-box-hero systemd[1]: man-db.service: Succeeded. Oct 30 08:25:25 juke-box-hero systemd[1]: Started Daily man-db regeneration. Oct 30 08:25:26 juke-box-hero ntpd[697]: Soliciting pool server 204.93.207.13 Oct 30 08:25:27 juke-box-hero volumio[958]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 7 Oct 30 08:25:28 juke-box-hero volumio[958]: info: AutoStart - Plugin is starting Oct 30 08:25:28 juke-box-hero volumio[958]: info: CoreCommandRouter::volumioGetQueue Oct 30 08:25:28 juke-box-hero volumio[958]: info: CoreStateMachine::getQueue Oct 30 08:25:28 juke-box-hero volumio[958]: info: CorePlayQueue::getQueue Oct 30 08:25:28 juke-box-hero volumio[958]: info: AutoStart - start playing Oct 30 08:25:28 juke-box-hero volumio[958]: info: AutoStart - start playing with no specific position Oct 30 08:25:28 juke-box-hero volumio[958]: info: CoreCommandRouter::volumioPlay Oct 30 08:25:28 juke-box-hero volumio[958]: info: CoreStateMachine::play index 0 Oct 30 08:25:28 juke-box-hero volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 08:25:28 juke-box-hero volumio[958]: info: CoreStateMachine::stop Oct 30 08:25:28 juke-box-hero volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 08:25:28 juke-box-hero volumio[958]: info: CoreStateMachine::play index undefined Oct 30 08:25:28 juke-box-hero volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 08:25:28 juke-box-hero volumio[958]: info: CorePlayQueue::getTrack 0 Oct 30 08:25:28 juke-box-hero volumio[958]: info: CoreStateMachine::startPlaybackTimer Oct 30 08:25:28 juke-box-hero volumio[958]: info: CorePlayQueue::getTrack 0 Oct 30 08:25:28 juke-box-hero volumio[958]: verbose: ControllerMpd::clearAddPlayTracks USB/321A-2A97/juke/Billboard Top Hits- 1983/01 Down Under.mp3 Oct 30 08:25:28 juke-box-hero volumio[958]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 08:25:28 juke-box-hero volumio[958]: info: sendMpdCommand stop took 9 milliseconds Oct 30 08:25:28 juke-box-hero volumio[958]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 08:25:28 juke-box-hero volumio[958]: info: Oct 30 08:25:28 juke-box-hero volumio[958]: ---------------------------- MPD announces system playlist update Oct 30 08:25:28 juke-box-hero volumio[958]: info: Ignoring MPD Status Update Oct 30 08:25:28 juke-box-hero volumio[958]: info: sendMpdCommand clear took 6 milliseconds Oct 30 08:25:28 juke-box-hero volumio[958]: verbose: ControllerMpd::sendMpdCommand add "USB/321A-2A97/juke/Billboard Top Hits- 1983/01 Down Under.mp3" Oct 30 08:25:28 juke-box-hero volumio[958]: error: updateQueue error: null Oct 30 08:25:28 juke-box-hero volumio[958]: info: Oct 30 08:25:28 juke-box-hero volumio[958]: ---------------------------- MPD announces system playlist update Oct 30 08:25:28 juke-box-hero volumio[958]: info: Ignoring MPD Status Update Oct 30 08:25:28 juke-box-hero volumio[958]: info: ------------------------------ 11ms Oct 30 08:25:28 juke-box-hero volumio[958]: info: sendMpdCommand add "USB/321A-2A97/juke/Billboard Top Hits- 1983/01 Down Under.mp3" took 5 milliseconds Oct 30 08:25:28 juke-box-hero volumio[958]: verbose: ControllerMpd::sendMpdCommand play Oct 30 08:25:28 juke-box-hero volumio[958]: info: ------------------------------ 17ms Oct 30 08:25:28 juke-box-hero volumio[958]: info: sendMpdCommand play took 4 milliseconds Oct 30 08:25:28 juke-box-hero volumio[958]: info: Oct 30 08:25:28 juke-box-hero volumio[958]: ---------------------------- MPD announces state update: player Oct 30 08:25:28 juke-box-hero volumio[958]: info: ControllerMpd::getState Oct 30 08:25:28 juke-box-hero volumio[958]: verbose: ControllerMpd::sendMpdCommand status Oct 30 08:25:28 juke-box-hero volumio[958]: info: Oct 30 08:25:28 juke-box-hero volumio[958]: ---------------------------- MPD announces state update: player Oct 30 08:25:28 juke-box-hero volumio[958]: info: sendMpdCommand status took 66 milliseconds Oct 30 08:25:28 juke-box-hero volumio[958]: info: ControllerMpd::getState Oct 30 08:25:28 juke-box-hero volumio[958]: verbose: ControllerMpd::sendMpdCommand status Oct 30 08:25:28 juke-box-hero volumio[958]: verbose: ControllerMpd::parseState Oct 30 08:25:28 juke-box-hero volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 08:25:28 juke-box-hero volumio[958]: info: sendMpdCommand status took 13 milliseconds Oct 30 08:25:28 juke-box-hero volumio[958]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 30 08:25:28 juke-box-hero volumio[958]: verbose: ControllerMpd::parseState Oct 30 08:25:28 juke-box-hero volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 08:25:28 juke-box-hero volumio[958]: verbose: ControllerMpd::parseTrackInfo Oct 30 08:25:28 juke-box-hero volumio[958]: info: ControllerMpd::pushState Oct 30 08:25:28 juke-box-hero volumio[958]: info: CoreCommandRouter::servicePushState Oct 30 08:25:28 juke-box-hero volumio[958]: info: CorePlayQueue::getTrack 0 Oct 30 08:25:28 juke-box-hero volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":225,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Down Under","artist":"Men at Work","album":"Billboard Top Hits: 1983","uri":"USB/321A-2A97/juke/Billboard Top Hits- 1983/01 Down Under.mp3","trackType":"mp3"} Oct 30 08:25:28 juke-box-hero volumio[958]: verbose: CURRENT POSITION 0 Oct 30 08:25:28 juke-box-hero volumio[958]: info: CoreStateMachine::syncState stateService play Oct 30 08:25:28 juke-box-hero volumio[958]: info: CoreStateMachine::syncState currentStatus stop Oct 30 08:25:28 juke-box-hero volumio[958]: info: ------------------------------ 105ms Oct 30 08:25:28 juke-box-hero volumio[958]: info: sendMpdCommand playlistinfo took 17 milliseconds Oct 30 08:25:28 juke-box-hero volumio[958]: verbose: ControllerMpd::parseTrackInfo Oct 30 08:25:28 juke-box-hero volumio[958]: info: ControllerMpd::pushState Oct 30 08:25:28 juke-box-hero volumio[958]: info: CoreCommandRouter::servicePushState Oct 30 08:25:28 juke-box-hero volumio[958]: info: CorePlayQueue::getTrack 0 Oct 30 08:25:28 juke-box-hero volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":225,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Down Under","artist":"Men at Work","album":"Billboard Top Hits: 1983","uri":"USB/321A-2A97/juke/Billboard Top Hits- 1983/01 Down Under.mp3","trackType":"mp3"} Oct 30 08:25:28 juke-box-hero volumio[958]: verbose: CURRENT POSITION 0 Oct 30 08:25:28 juke-box-hero volumio[958]: info: CoreStateMachine::syncState stateService play Oct 30 08:25:28 juke-box-hero volumio[958]: info: CoreStateMachine::syncState currentStatus play Oct 30 08:25:28 juke-box-hero volumio[958]: info: Received an update from plugin. extracting info from payload Oct 30 08:25:28 juke-box-hero volumio[958]: info: CoreStateMachine::pushState Oct 30 08:25:28 juke-box-hero volumio[958]: info: CorePlayQueue::getTrack 0 Oct 30 08:25:28 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 08:25:28 juke-box-hero volumio[958]: info: CoreCommandRouter::volumioPushState Oct 30 08:25:28 juke-box-hero volumio[958]: info: CoreStateMachine::pushState Oct 30 08:25:28 juke-box-hero volumio[958]: info: CorePlayQueue::getTrack 0 Oct 30 08:25:28 juke-box-hero volumio[958]: info: CoreCommandRouter::volumioPushState Oct 30 08:25:28 juke-box-hero volumio[958]: info: ------------------------------ 156ms Oct 30 08:25:28 juke-box-hero volumio[958]: info: [LastFM] Current track has sufficient metadata: title (Down Under) and artist (Men at Work) passed on explicitly Oct 30 08:25:28 juke-box-hero volumio[958]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Oct 30 08:25:29 juke-box-hero kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Oct 30 08:25:29 juke-box-hero volumio[958]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 8 Oct 30 08:25:29 juke-box-hero systemd[1]: apt-daily.service: Succeeded. Oct 30 08:25:29 juke-box-hero systemd[1]: Started Daily apt download activities. Oct 30 08:25:29 juke-box-hero systemd[1]: Starting Daily apt upgrade and clean activities... Oct 30 08:25:30 juke-box-hero volumio[958]: info: CoreCommandRouter::volumioGetState Oct 30 08:25:30 juke-box-hero volumio[958]: info: CorePlayQueue::getTrack 0 Oct 30 08:25:30 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 30 08:25:30 juke-box-hero wpa_supplicant[834]: wlan0: Failed to initiate sched scan Oct 30 08:25:30 juke-box-hero volumio[958]: info: Listing playlists Oct 30 08:25:30 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 30 08:25:30 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 30 08:25:30 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 30 08:25:30 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 30 08:25:32 juke-box-hero systemd[1]: apt-daily-upgrade.service: Succeeded. Oct 30 08:25:32 juke-box-hero systemd[1]: Started Daily apt upgrade and clean activities. Oct 30 08:25:35 juke-box-hero wpa_supplicant[834]: wlan0: Failed to initiate sched scan Oct 30 08:25:38 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 08:25:38 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 08:25:38 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 30 08:25:38 juke-box-hero volumio[958]: error: Plugin music_service ytmusic failed to complete 'onStart' in a timely fashion Oct 30 08:25:38 juke-box-hero volumio[958]: info: ------------------------------------------- Oct 30 08:25:38 juke-box-hero volumio[958]: info: ----- MyVolumio plugins startup ---- Oct 30 08:25:38 juke-box-hero volumio[958]: info: ------------------------------------------- Oct 30 08:25:38 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 30 08:25:38 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 30 08:25:39 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 30 08:25:41 juke-box-hero wpa_supplicant[834]: wlan0: Failed to initiate sched scan Oct 30 08:25:42 juke-box-hero volumio[1092]: .....................................................................................................................................................................................................................++++ Oct 30 08:25:46 juke-box-hero wpa_supplicant[834]: wlan0: Failed to initiate sched scan Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 30 08:25:47 juke-box-hero volumio[958]: info: Adding plugin bluetooth to MyMusic Plugins Oct 30 08:25:47 juke-box-hero volumio[958]: info: Adding plugin multiroom to MyMusic Plugins Oct 30 08:25:47 juke-box-hero volumio[958]: info: Adding plugin metavolumio to MyMusic Plugins Oct 30 08:25:47 juke-box-hero volumio[958]: info: Adding plugin cd_controller to MyMusic Plugins Oct 30 08:25:47 juke-box-hero volumio[958]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 30 08:25:47 juke-box-hero volumio[958]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 30 08:25:47 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 30 08:25:48 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 30 08:25:48 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 30 08:25:48 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 08:25:48 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 08:25:48 juke-box-hero volumio[958]: info: Starting MyVolumio Remote Streaming Endpoints Oct 30 08:25:48 juke-box-hero volumio[958]: info: MyVolumio login type: Token Oct 30 08:25:48 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 30 08:25:48 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 30 08:25:50 juke-box-hero volumio[958]: info: Starting Streaming Service Transparent Proxy Oct 30 08:25:50 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 30 08:25:50 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 30 08:25:50 juke-box-hero volumio[958]: info: Streaming services startup Oct 30 08:25:50 juke-box-hero volumio[958]: info: Starting Streaming Daemon Oct 30 08:25:50 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 30 08:25:50 juke-box-hero sudo[1740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 30 08:25:50 juke-box-hero sudo[1740]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 08:25:50 juke-box-hero sudo[1740]: pam_unix(sudo:session): session closed for user root Oct 30 08:25:50 juke-box-hero volumio[958]: error: Cannot start Volumio Streaming Daemon Oct 30 08:25:50 juke-box-hero volumio[958]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 30 08:25:50 juke-box-hero volumio[958]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 30 08:25:50 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 30 08:25:50 juke-box-hero volumio[958]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 30 08:25:50 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 30 08:25:50 juke-box-hero volumio[958]: info: Received Get System Version Oct 30 08:25:50 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 08:25:50 juke-box-hero volumio[958]: info: Received Get System Info Oct 30 08:25:50 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 08:25:50 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 08:25:50 juke-box-hero volumio[958]: info: Discovery: Getting this device information Oct 30 08:25:50 juke-box-hero volumio[958]: info: CoreCommandRouter::volumioGetState Oct 30 08:25:50 juke-box-hero volumio[958]: info: CorePlayQueue::getTrack 0 Oct 30 08:25:50 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 08:25:50 juke-box-hero volumio[958]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 30 08:25:50 juke-box-hero volumio[958]: info: MYVOLUMIO: Adding device Oct 30 08:25:50 juke-box-hero volumio[958]: info: MYVOLUMIO: Evaluating Server Oct 30 08:25:51 juke-box-hero volumio[958]: info: MyVolumio Plan changed: premium Oct 30 08:25:51 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Oct 30 08:25:51 juke-box-hero volumio[958]: info: Removing browser output: myVolumio user plan is not superstar Oct 30 08:25:51 juke-box-hero volumio[958]: info: Removing audio output: Oct 30 08:25:51 juke-box-hero volumio[958]: info: MYVOLUMIO: Adding device Oct 30 08:25:51 juke-box-hero volumio[958]: info: MYVOLUMIO: Evaluating Server Oct 30 08:25:52 juke-box-hero wpa_supplicant[834]: wlan0: Failed to initiate sched scan Oct 30 08:25:52 juke-box-hero volumio[958]: info: Setting Geolocation for MyVolumio to us3 Oct 30 08:25:52 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 08:25:52 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 08:25:52 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 08:25:52 juke-box-hero volumio[958]: info: Remote config written successfully Oct 30 08:25:52 juke-box-hero volumio[958]: info: Starting Tunnel 1 Oct 30 08:25:52 juke-box-hero volumio[958]: info: Starting Tunnel Connection Checker Oct 30 08:25:52 juke-box-hero volumio[958]: info: Completed starting MyVolumio Plugin Oct 30 08:25:52 juke-box-hero volumio[958]: info: MYVolumio Device enabled Oct 30 08:25:52 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Oct 30 08:25:52 juke-box-hero volumio[958]: info: MyVolumio status changed Oct 30 08:25:52 juke-box-hero volumio[958]: info: Streaming services startup Oct 30 08:25:52 juke-box-hero volumio[958]: info: Starting Streaming Daemon Oct 30 08:25:52 juke-box-hero sudo[1780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 30 08:25:52 juke-box-hero sudo[1780]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 08:25:52 juke-box-hero sudo[1780]: pam_unix(sudo:session): session closed for user root Oct 30 08:25:52 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Oct 30 08:25:52 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Oct 30 08:25:52 juke-box-hero volumio[958]: STREAMING PROXY: Starting server on port 3245 Oct 30 08:25:52 juke-box-hero volumio[958]: Node JS runtime: 14 Oct 30 08:25:52 juke-box-hero volumio[958]: info: [1730294752976] Starting BluetoothController Oct 30 08:25:53 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Oct 30 08:25:54 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Oct 30 08:25:54 juke-box-hero sudo[1783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /myvolumio/plugins/audio_interface/multiroom/scripts/check_snapcast Oct 30 08:25:54 juke-box-hero sudo[1783]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 08:25:54 juke-box-hero sudo[1783]: pam_unix(sudo:session): session closed for user root Oct 30 08:25:54 juke-box-hero sudo[1786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 30 08:25:55 juke-box-hero sudo[1786]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 08:25:55 juke-box-hero sudo[1786]: pam_unix(sudo:session): session closed for user root Oct 30 08:25:55 juke-box-hero volumio[958]: info: MRS: MultiRoom plugin initialized Oct 30 08:25:55 juke-box-hero volumio[958]: info: MRS: STOPPING SNAPCLIENT Oct 30 08:25:55 juke-box-hero volumio[958]: info: MRS: Snap server stop Oct 30 08:25:55 juke-box-hero sudo[1803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 30 08:25:55 juke-box-hero sudo[1803]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 08:25:55 juke-box-hero volumio[958]: info: MRS: STOPPING volumioStreaming Oct 30 08:25:55 juke-box-hero sudo[1803]: pam_unix(sudo:session): session closed for user root Oct 30 08:25:55 juke-box-hero sudo[1806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 30 08:25:55 juke-box-hero sudo[1806]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 08:25:55 juke-box-hero sudo[1808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 30 08:25:55 juke-box-hero sudo[1808]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 08:25:55 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Oct 30 08:25:55 juke-box-hero sudo[1812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 30 08:25:55 juke-box-hero sudo[1812]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 08:25:55 juke-box-hero sudo[1812]: pam_unix(sudo:session): session closed for user root Oct 30 08:25:55 juke-box-hero sudo[1806]: pam_unix(sudo:session): session closed for user root Oct 30 08:25:55 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Oct 30 08:25:55 juke-box-hero sudo[1808]: pam_unix(sudo:session): session closed for user root Oct 30 08:25:55 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Oct 30 08:25:55 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Oct 30 08:25:55 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Oct 30 08:25:55 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Oct 30 08:25:55 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Oct 30 08:25:55 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Oct 30 08:25:55 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Oct 30 08:25:55 juke-box-hero volumio[958]: info: Preparing to generate the ALSA configuration file Oct 30 08:25:55 juke-box-hero volumio[958]: error: Cannot start Volumio Streaming Daemon Oct 30 08:25:55 juke-box-hero volumio[958]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 30 08:25:55 juke-box-hero volumio[958]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 30 08:25:55 juke-box-hero volumio[958]: info: Updating MyVolumio device info Oct 30 08:25:55 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 08:25:55 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 08:25:55 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 08:25:55 juke-box-hero volumio[958]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 30 08:25:55 juke-box-hero volumio[958]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 30 08:25:55 juke-box-hero volumio[958]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 30 08:25:55 juke-box-hero volumio[958]: info: Reading ALSA contributions from plugins. Oct 30 08:25:55 juke-box-hero volumio[958]: info: Setting Geolocation for MyVolumio to us3 Oct 30 08:25:55 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 08:25:55 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 08:25:55 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 08:25:55 juke-box-hero volumio[958]: info: Successfully Added MyVolumio device Oct 30 08:25:55 juke-box-hero volumio[958]: info: MRS: Removed streaming files Oct 30 08:25:55 juke-box-hero volumio[958]: info: MRS: volumioStreaming STOPPED Oct 30 08:25:55 juke-box-hero volumio[958]: info: MRS: SNAPSERVER STOPPED Oct 30 08:25:55 juke-box-hero volumio[958]: info: MRS: SNAPCLIENT STOPPED Oct 30 08:25:55 juke-box-hero volumio[958]: info: Snap server and client installed Oct 30 08:25:55 juke-box-hero volumio[958]: info: Asound.conf file written Oct 30 08:25:55 juke-box-hero sudo[1815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 30 08:25:55 juke-box-hero sudo[1815]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 08:25:55 juke-box-hero sudo[1815]: pam_unix(sudo:session): session closed for user root Oct 30 08:25:56 juke-box-hero volumio[958]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy Oct 30 08:25:56 juke-box-hero volumio[958]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy Oct 30 08:25:56 juke-box-hero volumio[958]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy Oct 30 08:25:56 juke-box-hero volumio[958]: info: Output device has changed, restarting MPD Oct 30 08:25:56 juke-box-hero sudo[1820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 30 08:25:56 juke-box-hero sudo[1820]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 08:25:56 juke-box-hero sudo[1820]: pam_unix(sudo:session): session closed for user root Oct 30 08:25:56 juke-box-hero volumio[958]: info: Output device has changed, restarting Shairport Sync Oct 30 08:25:56 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 08:25:56 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 08:25:56 juke-box-hero sudo[1823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 30 08:25:56 juke-box-hero sudo[1823]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 08:25:56 juke-box-hero systemd[1]: Stopping Music Player Daemon... Oct 30 08:25:56 juke-box-hero volumio[958]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 30 08:25:56 juke-box-hero volumio[958]: [SpotifyConnect] Creating VLS config file Oct 30 08:25:56 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 08:25:56 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 08:25:56 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 08:25:56 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 08:25:56 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 08:25:56 juke-box-hero volumio[958]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 30 08:25:56 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 08:25:56 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Oct 30 08:25:56 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 30 08:25:56 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Oct 30 08:25:56 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 30 08:25:56 juke-box-hero volumio[958]: info: MRS: Pushing multiroomSync output for this device Oct 30 08:25:56 juke-box-hero volumio[958]: info: MRS: Pushing multiroomSync output Oct 30 08:25:56 juke-box-hero sudo[1831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Juke Box Hero Oct 30 08:25:56 juke-box-hero sudo[1831]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 08:25:56 juke-box-hero volumio[958]: info: Adding audio output: Oct 30 08:25:56 juke-box-hero volumio[958]: info: Adding audio output: Oct 30 08:25:56 juke-box-hero sudo[1831]: pam_unix(sudo:session): session closed for user root Oct 30 08:25:56 juke-box-hero volumio[958]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 30 08:25:56 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 08:25:56 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Oct 30 08:25:56 juke-box-hero volumio[958]: info: Adding METAVOLUMIO REST API Endpoints Oct 30 08:25:56 juke-box-hero volumio[958]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 30 08:25:56 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Oct 30 08:25:56 juke-box-hero volumio[958]: info: Adding Manifest REST API Endpoints Oct 30 08:25:56 juke-box-hero volumio[958]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Oct 30 08:25:56 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Oct 30 08:25:56 juke-box-hero volumio[958]: info: Preparing CD Folders Oct 30 08:25:56 juke-box-hero volumio[958]: info: Adding CD REST API Endpoints Oct 30 08:25:56 juke-box-hero volumio[958]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 30 08:25:56 juke-box-hero volumio[958]: info: Starting UDEV Watcher for CD Oct 30 08:25:56 juke-box-hero volumio[958]: info: Detecting CD presence with UDEV Oct 30 08:25:56 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Oct 30 08:25:56 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Oct 30 08:25:56 juke-box-hero volumio[958]: info: Adding inputs REST Endpoints Oct 30 08:25:56 juke-box-hero volumio[958]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Oct 30 08:25:56 juke-box-hero volumio[958]: info: Scanning Audio Inputs Oct 30 08:25:56 juke-box-hero volumio[958]: info: Checking against Known Cards name Oct 30 08:25:56 juke-box-hero volumio[958]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 08:25:56 juke-box-hero volumio[958]: info: [1730294756765] CoreMusicLibrary::Adding element HiFiBerry ADC Oct 30 08:25:56 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 08:25:56 juke-box-hero volumio[958]: Cannot find translation for sourceMedia Servers Oct 30 08:25:56 juke-box-hero volumio[958]: Cannot find translation for sourceYouTube2 Oct 30 08:25:56 juke-box-hero volumio[958]: Cannot find translation for source80s80s Radio Oct 30 08:25:56 juke-box-hero volumio[958]: Cannot find translation for sourceLastFM Oct 30 08:25:56 juke-box-hero volumio[958]: Cannot find translation for sourceHiFiBerry ADC Oct 30 08:25:56 juke-box-hero volumio[958]: info: Checking against Known Cards name Oct 30 08:25:56 juke-box-hero volumio[958]: info: Adding Server instance for streaming Oct 30 08:25:56 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Oct 30 08:25:56 juke-box-hero volumio[958]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 30 08:25:56 juke-box-hero volumio[958]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 30 08:25:56 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Oct 30 08:25:56 juke-box-hero volumio[958]: info: Refreshing TIDAL token Oct 30 08:25:56 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Oct 30 08:25:56 juke-box-hero volumio[958]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Oct 30 08:25:56 juke-box-hero sudo[1842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 30 08:25:56 juke-box-hero sudo[1842]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 08:25:56 juke-box-hero volumio[958]: info: Stopping AccessToken refresher cron for QOBUZ Oct 30 08:25:56 juke-box-hero sudo[1842]: pam_unix(sudo:session): session closed for user root Oct 30 08:25:56 juke-box-hero volumio[958]: info: AccessToken refresher cron started for QOBUZ Oct 30 08:25:56 juke-box-hero volumio[958]: info: Adding QOBUZ REST API Endpoints Oct 30 08:25:56 juke-box-hero volumio[958]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Oct 30 08:25:56 juke-box-hero volumio[958]: [SpotifyConnect] Restarting Vollibrespot Daemon Oct 30 08:25:56 juke-box-hero volumio[958]: info: Updating MyVolumio device info Oct 30 08:25:56 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 08:25:56 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 08:25:56 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 08:25:56 juke-box-hero sudo[1846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Oct 30 08:25:56 juke-box-hero sudo[1846]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 08:25:56 juke-box-hero systemd[1]: Stopping Volspotconnect2 Daemon... Oct 30 08:25:57 juke-box-hero systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Oct 30 08:25:57 juke-box-hero systemd[1]: volspotconnect.service: Succeeded. Oct 30 08:25:57 juke-box-hero systemd[1]: Stopped Volspotconnect2 Daemon. Oct 30 08:25:57 juke-box-hero systemd[1]: Started Volspotconnect2 Daemon. Oct 30 08:25:57 juke-box-hero volumio[958]: info: Bluetooth name changed to Juke Box Hero Oct 30 08:25:57 juke-box-hero sudo[1846]: pam_unix(sudo:session): session closed for user root Oct 30 08:25:57 juke-box-hero volumio[958]: info: MPD Permissions set Oct 30 08:25:57 juke-box-hero volumio[1848]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Oct 30 08:25:57 juke-box-hero volumio[1848]: Reading Config from "/tmp/volspotify.toml" Oct 30 08:25:57 juke-box-hero volumio[1848]: [Vollibrespot] : Using Alsa backend with device: volumio Oct 30 08:25:57 juke-box-hero volumio[958]: [SpotifyConnect] Vollibrespot Daemon service restarted! Oct 30 08:25:57 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 08:25:57 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 08:25:57 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 08:25:57 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 08:25:57 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 08:25:57 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 08:25:57 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 08:25:57 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 08:25:57 juke-box-hero volumio[1848]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Oct 30 08:25:57 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 08:25:57 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 08:25:57 juke-box-hero volumio[958]: info: Starting Shairport Sync Oct 30 08:25:57 juke-box-hero sudo[1855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 30 08:25:57 juke-box-hero sudo[1855]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 08:25:57 juke-box-hero systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 30 08:25:57 juke-box-hero systemd[1]: shairport-sync.service: Succeeded. Oct 30 08:25:57 juke-box-hero systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 30 08:25:57 juke-box-hero systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 30 08:25:57 juke-box-hero sudo[1855]: pam_unix(sudo:session): session closed for user root Oct 30 08:25:57 juke-box-hero volumio[958]: info: Shairport-Sync Started Oct 30 08:25:57 juke-box-hero volumio[958]: info: Successfully Added MyVolumio device Oct 30 08:25:57 juke-box-hero systemd[1]: mpd.service: Succeeded. Oct 30 08:25:57 juke-box-hero systemd[1]: Stopped Music Player Daemon. Oct 30 08:25:57 juke-box-hero systemd[1]: Starting Music Player Daemon... Oct 30 08:25:57 juke-box-hero volumio[958]: info: Access Token successfully retrieved Oct 30 08:25:57 juke-box-hero volumio[958]: info: Successfully Updated MyVolumio device Oct 30 08:25:57 juke-box-hero volumio[958]: info: Successfully retrieved User Session From TIDAL Oct 30 08:25:57 juke-box-hero volumio[958]: info: Successfully Updated MyVolumio device Oct 30 08:25:57 juke-box-hero sudo[1868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 30 08:25:57 juke-box-hero sudo[1868]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 08:25:57 juke-box-hero volumio[958]: info: Successfully retrieved User Subscription From TIDAL Oct 30 08:25:57 juke-box-hero volumio[958]: info: Adding TIDAL to Browse Sources Oct 30 08:25:57 juke-box-hero volumio[958]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 08:25:57 juke-box-hero volumio[958]: info: [1730294757813] CoreMusicLibrary::Adding element TIDAL Oct 30 08:25:57 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 08:25:57 juke-box-hero volumio[958]: Cannot find translation for sourceMedia Servers Oct 30 08:25:57 juke-box-hero volumio[958]: Cannot find translation for sourceYouTube2 Oct 30 08:25:57 juke-box-hero volumio[958]: Cannot find translation for source80s80s Radio Oct 30 08:25:57 juke-box-hero volumio[958]: Cannot find translation for sourceLastFM Oct 30 08:25:57 juke-box-hero volumio[958]: Cannot find translation for sourceHiFiBerry ADC Oct 30 08:25:57 juke-box-hero volumio[958]: Cannot find translation for sourceTIDAL Oct 30 08:25:57 juke-box-hero volumio[958]: info: Stopping AccessToken refresher cron Oct 30 08:25:57 juke-box-hero volumio[958]: info: AccessToken refresher cron started Oct 30 08:25:57 juke-box-hero volumio[958]: info: Adding TIDAL REST API Endpoints Oct 30 08:25:57 juke-box-hero volumio[958]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Oct 30 08:25:57 juke-box-hero systemd[1]: Started MyVolumio SSH Tunnel. Oct 30 08:25:57 juke-box-hero sudo[1868]: pam_unix(sudo:session): session closed for user root Oct 30 08:25:57 juke-box-hero volumio[958]: info: Remote SSH Started Oct 30 08:25:57 juke-box-hero autossh[1871]: port set to 0, monitoring disabled Oct 30 08:25:57 juke-box-hero autossh[1871]: starting ssh (count 1) Oct 30 08:25:57 juke-box-hero autossh[1871]: ssh child pid is 1874 Oct 30 08:25:58 juke-box-hero wpa_supplicant[834]: wlan0: Failed to initiate sched scan Oct 30 08:25:58 juke-box-hero volumio[958]: error: Hi Res Audio Failed Login: [object Object] Oct 30 08:25:58 juke-box-hero volumiossh-tunnel[1870]: Warning: Permanently added '[us3.myvolumio.org]:2222,[159.203.57.91]:2222' (RSA) to the list of known hosts. Oct 30 08:25:58 juke-box-hero volumio[958]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Oct 30 08:25:58 juke-box-hero volumio[958]: info: CoreCommandRouter::volumioGetState Oct 30 08:25:58 juke-box-hero volumio[958]: info: CorePlayQueue::getTrack 0 Oct 30 08:25:59 juke-box-hero sudo[1876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 30 08:25:59 juke-box-hero sudo[1876]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 08:25:59 juke-box-hero systemd[1]: Started Volumio Bluetooth Module. Oct 30 08:25:59 juke-box-hero sudo[1876]: pam_unix(sudo:session): session closed for user root Oct 30 08:25:59 juke-box-hero volumio[958]: info: Volumio BT Module successfully started Oct 30 08:25:59 juke-box-hero sudo[1879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Oct 30 08:25:59 juke-box-hero sudo[1879]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 08:25:59 juke-box-hero volumiobt[1878]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Oct 30 08:25:59 juke-box-hero sudo[1879]: pam_unix(sudo:session): session closed for user root Oct 30 08:25:59 juke-box-hero mpd[1865]: Oct 30 08:25 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 30 08:25:59 juke-box-hero mpd[1865]: Oct 30 08:25 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Oct 30 08:25:59 juke-box-hero mpd[1865]: Oct 30 08:25 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Oct 30 08:25:59 juke-box-hero systemd[1]: Started Music Player Daemon. Oct 30 08:25:59 juke-box-hero sudo[1823]: pam_unix(sudo:session): session closed for user root Oct 30 08:25:59 juke-box-hero volumio[958]: error: updateQueue error: null Oct 30 08:25:59 juke-box-hero volumio[958]: info: TidalConnect service stoped! Oct 30 08:26:00 juke-box-hero volumio[958]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 30 08:26:00 juke-box-hero volumio[958]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 30 08:26:00 juke-box-hero sudo[1893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 30 08:26:00 juke-box-hero sudo[1893]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 08:26:00 juke-box-hero systemd[1]: Started Volumio Tidal Connect Service. Oct 30 08:26:00 juke-box-hero sudo[1893]: pam_unix(sudo:session): session closed for user root Oct 30 08:26:00 juke-box-hero sudo[1898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Oct 30 08:26:00 juke-box-hero sudo[1898]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 08:26:00 juke-box-hero volumiobt[1878]: pulseaudio: no process found Oct 30 08:26:00 juke-box-hero sudo[1898]: pam_unix(sudo:session): session closed for user root Oct 30 08:26:00 juke-box-hero volumio[958]: info: Executing endpoint tc_getconfig Oct 30 08:26:00 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 30 08:26:00 juke-box-hero dbus-daemon[539]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.24' (uid=1000 pid=1903 comm="/usr/bin/pulseaudio --high-priority=true ") Oct 30 08:26:00 juke-box-hero vtcs[1895]: STARTING TidalConnect services, version: 1.1.7.5 Oct 30 08:26:00 juke-box-hero vtcs[1895]: STARTED TidalConnect services. Oct 30 08:26:00 juke-box-hero systemd[1]: Starting RealtimeKit Scheduling Policy Service... Oct 30 08:26:00 juke-box-hero volumio[958]: info: Executing endpoint tc_connect Oct 30 08:26:00 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 30 08:26:00 juke-box-hero volumio[958]: info: Connecting to TidalConnect Oct 30 08:26:00 juke-box-hero dbus-daemon[539]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Oct 30 08:26:00 juke-box-hero systemd[1]: Started RealtimeKit Scheduling Policy Service. Oct 30 08:26:00 juke-box-hero volumio[958]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 08:26:00 juke-box-hero rtkit-daemon[1909]: Successfully called chroot. Oct 30 08:26:00 juke-box-hero rtkit-daemon[1909]: Successfully dropped privileges. Oct 30 08:26:00 juke-box-hero rtkit-daemon[1909]: Successfully limited resources. Oct 30 08:26:00 juke-box-hero rtkit-daemon[1909]: Running. Oct 30 08:26:00 juke-box-hero rtkit-daemon[1909]: Canary thread running. Oct 30 08:26:00 juke-box-hero dbus-daemon[539]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.25' (uid=0 pid=1909 comm="/usr/lib/rtkit/rtkit-daemon ") Oct 30 08:26:00 juke-box-hero rtkit-daemon[1909]: Watchdog thread running. Oct 30 08:26:00 juke-box-hero volumio[958]: info: CoreCommandRouter::servicePushState Oct 30 08:26:00 juke-box-hero volumio[958]: info: CoreStateMachine::pushState Oct 30 08:26:00 juke-box-hero volumio[958]: info: CorePlayQueue::getTrack 0 Oct 30 08:26:00 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 08:26:00 juke-box-hero volumio[958]: info: CoreCommandRouter::volumioPushState Oct 30 08:26:00 juke-box-hero systemd[1]: Starting Authorization Manager... Oct 30 08:26:00 juke-box-hero volumio[958]: info: MRS: Pushing multiroomSync output update for this device Oct 30 08:26:00 juke-box-hero volumio[958]: info: MRS: Pushing multiroomSync output Oct 30 08:26:00 juke-box-hero volumio[958]: info: CorePlayQueue::getTrack 0 Oct 30 08:26:00 juke-box-hero volumio[958]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 30 08:26:00 juke-box-hero volumio[958]: info: CoreCommandRouter::servicePushState Oct 30 08:26:00 juke-box-hero volumio[958]: info: CoreStateMachine::pushState Oct 30 08:26:00 juke-box-hero volumio[958]: info: CorePlayQueue::getTrack 0 Oct 30 08:26:00 juke-box-hero volumio[958]: info: CoreCommandRouter::volumioPushState Oct 30 08:26:00 juke-box-hero volumio[958]: info: MRS: Pushing multiroomSync output update for this device Oct 30 08:26:00 juke-box-hero volumio[958]: info: MRS: Pushing multiroomSync output Oct 30 08:26:00 juke-box-hero volumio[958]: info: CorePlayQueue::getTrack 0 Oct 30 08:26:00 juke-box-hero volumio[958]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 30 08:26:00 juke-box-hero volumio[958]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 08:26:00 juke-box-hero polkitd[1914]: started daemon version 0.105 using authority implementation `local' version `0.105' Oct 30 08:26:00 juke-box-hero dbus-daemon[539]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Oct 30 08:26:00 juke-box-hero systemd[1]: Started Authorization Manager. Oct 30 08:26:01 juke-box-hero volumio[958]: info: MRS: Getting audio outputs on start Oct 30 08:26:01 juke-box-hero volumio[958]: info: MRS: Requesting all other devices output Oct 30 08:26:01 juke-box-hero pulseaudio[1903]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 30 08:26:01 juke-box-hero pulseaudio[1903]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 30 08:26:01 juke-box-hero bluetoothd[730]: Endpoint registered: sender=:1.46 path=/MediaEndpoint/A2DPSource Oct 30 08:26:01 juke-box-hero bluetoothd[730]: Endpoint registered: sender=:1.46 path=/MediaEndpoint/A2DPSink Oct 30 08:26:01 juke-box-hero pulseaudio[1903]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Oct 30 08:26:01 juke-box-hero volumiobt[1878]: Applying permissions Oct 30 08:26:01 juke-box-hero volumiobt[1878]: Setting BT discoverable and pairable Oct 30 08:26:01 juke-box-hero kernel: Bluetooth: RFCOMM TTY layer initialized Oct 30 08:26:01 juke-box-hero kernel: Bluetooth: RFCOMM socket layer initialized Oct 30 08:26:01 juke-box-hero kernel: Bluetooth: RFCOMM ver 1.11 Oct 30 08:26:01 juke-box-hero volumiobt[1878]: Agent registered Oct 30 08:26:01 juke-box-hero volumiobt[1878]: [117B blob data] Oct 30 08:26:01 juke-box-hero volumiobt[1878]: [117B blob data] Oct 30 08:26:01 juke-box-hero volumiobt[1878]: [117B blob data] Oct 30 08:26:01 juke-box-hero volumiobt[1878]: [117B blob data] Oct 30 08:26:01 juke-box-hero volumiobt[1878]: [117B blob data] Oct 30 08:26:01 juke-box-hero volumiobt[1878]: [117B blob data] Oct 30 08:26:01 juke-box-hero volumiobt[1878]: [117B blob data] Oct 30 08:26:01 juke-box-hero volumiobt[1878]: [117B blob data] Oct 30 08:26:01 juke-box-hero volumiobt[1878]: [117B blob data] Oct 30 08:26:01 juke-box-hero volumiobt[1878]: [bluetooth]# -e power on Oct 30 08:26:01 juke-box-hero volumiobt[1878]: Invalid command in menu main: -e Oct 30 08:26:01 juke-box-hero volumiobt[1878]: Oct 30 08:26:01 juke-box-hero volumiobt[1878]: Use "help" for a list of available commands in a menu. Oct 30 08:26:01 juke-box-hero volumiobt[1878]: Use "menu " if you want to enter any submenu. Oct 30 08:26:01 juke-box-hero volumiobt[1878]: Use "back" if you want to return to menu main. Oct 30 08:26:01 juke-box-hero volumiobt[1878]: [bluetooth]# agent on Oct 30 08:26:01 juke-box-hero volumiobt[1878]: Agent is already registered Oct 30 08:26:01 juke-box-hero volumiobt[1878]: [bluetooth]# discoverable on Oct 30 08:26:01 juke-box-hero volumiobt[1878]: [bluetooth]# pairable on Oct 30 08:26:01 juke-box-hero volumiobt[1878]: [bluetooth]# agent NoInputNoOutput Oct 30 08:26:01 juke-box-hero volumiobt[1878]: Agent is already registered Oct 30 08:26:01 juke-box-hero volumiobt[1878]: [bluetooth]# default-agent Oct 30 08:26:01 juke-box-hero volumiobt[1878]: [bluetooth]# quit Oct 30 08:26:01 juke-box-hero volumiobt[1878]: [59B blob data] Oct 30 08:26:03 juke-box-hero volumio[958]: info: Checking Spotify Web API Oct 30 08:26:03 juke-box-hero volumio[958]: info: TidalConnect service started! Oct 30 08:26:03 juke-box-hero volumio[958]: info: BOOT COMPLETED Oct 30 08:26:03 juke-box-hero volumio[958]: [Metrics] CommandRouter: 81s 319.47ms Oct 30 08:26:03 juke-box-hero volumio[958]: info: CoreCommandRouter::volumiosetStartupVolume Oct 30 08:26:03 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 08:26:03 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 08:26:03 juke-box-hero volumio[958]: info: CoreCommandRouter::Close All Modals sent Oct 30 08:26:03 juke-box-hero volumio[958]: info: CoreCommandRouter::Close All Modals sent Oct 30 08:26:03 juke-box-hero kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Oct 30 08:26:03 juke-box-hero wpa_supplicant[834]: wlan0: Failed to initiate sched scan Oct 30 08:26:03 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 30 08:26:03 juke-box-hero volumio[958]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Oct 30 08:26:03 juke-box-hero volumio[958]: info: CoreCommandRouter::volumioGetState Oct 30 08:26:03 juke-box-hero volumio[958]: info: CorePlayQueue::getTrack 0 Oct 30 08:26:04 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 30 08:26:04 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 30 08:26:04 juke-box-hero volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 30 08:26:09 juke-box-hero wpa_supplicant[834]: wlan0: Failed to initiate sched scan Oct 30 08:26:09 juke-box-hero volumio[1092]: ..........................................................................................................................................................................................++++ Oct 30 08:26:09 juke-box-hero volumio[1092]: e is 65537 (0x010001) Oct 30 08:26:09 juke-box-hero volumio[1092]: writing RSA key Oct 30 08:26:14 juke-box-hero wpa_supplicant[834]: wlan0: Failed to initiate sched scan Oct 30 08:26:20 juke-box-hero wpa_supplicant[834]: wlan0: Failed to initiate sched scan Oct 30 08:26:25 juke-box-hero wpa_supplicant[834]: wlan0: Failed to initiate sched scan Oct 30 08:26:31 juke-box-hero wpa_supplicant[834]: wlan0: Failed to initiate sched scan Oct 30 08:26:36 juke-box-hero wpa_supplicant[834]: wlan0: Failed to initiate sched scan Oct 30 08:26:42 juke-box-hero wpa_supplicant[834]: wlan0: Failed to initiate sched scan Oct 30 08:26:47 juke-box-hero wpa_supplicant[834]: wlan0: Failed to initiate sched scan Oct 30 08:26:53 juke-box-hero wpa_supplicant[834]: wlan0: Failed to initiate sched scan Oct 30 08:26:54 juke-box-hero volumio[958]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 08:26:54 juke-box-hero volumio[958]: Error: dns service error: unknown Oct 30 08:26:54 juke-box-hero volumio[958]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Oct 30 08:26:54 juke-box-hero volumio[958]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Oct 30 08:26:54 juke-box-hero volumio[958]: errorCode: -65537 Oct 30 08:26:54 juke-box-hero volumio[958]: } Oct 30 08:26:54 juke-box-hero volumio[958]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 08:26:55 juke-box-hero sudo[1976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-30 08:25 Oct 30 08:26:55 juke-box-hero sudo[1976]: 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="68644ecc3d95554759c26a672c9f9845659ebf49" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="d1eed292e757ec0ddcc4830aa293d402cdfd9c92" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 06 Mar 2023 10:42:18 AM CET" VOLUMIO_VERSION="3.435" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cb521a82390d29f21453532486094e99"