Dec 15 14:03:06 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 14:03:06 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 14:03:06 localhost volumio[1397]: info: Discovery: Getting this device information Dec 15 14:03:06 localhost volumio[1397]: info: CoreCommandRouter::volumioGetState Dec 15 14:03:06 localhost volumio[1397]: info: CorePlayQueue::getTrack 0 Dec 15 14:03:06 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 14:03:06 localhost volumio[1397]: verbose: New Socket.io Connection to 192.168.0.65:3000 from 192.168.0.20 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Dec 15 14:03:06 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 15 14:03:06 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 15 14:03:06 localhost volumio[1397]: verbose: New Socket.io Connection to 192.168.0.65:3000 from 192.168.0.20 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Dec 15 14:03:06 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 14:03:06 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 14:03:06 localhost volumio[1397]: info: Discovery: Getting this device information Dec 15 14:03:06 localhost volumio[1397]: info: CoreCommandRouter::volumioGetState Dec 15 14:03:06 localhost volumio[1397]: info: CorePlayQueue::getTrack 0 Dec 15 14:03:06 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 14:03:08 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 14:03:08 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 14:03:08 localhost volumio[1397]: info: Discovery: Getting this device information Dec 15 14:03:08 localhost volumio[1397]: info: CoreCommandRouter::volumioGetState Dec 15 14:03:08 localhost volumio[1397]: info: CorePlayQueue::getTrack 0 Dec 15 14:03:08 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 14:03:08 localhost volumio[1397]: verbose: New Socket.io Connection to 192.168.0.65:3000 from 192.168.0.20 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 15 14:03:08 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 15 14:03:08 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 15 14:03:10 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 14:03:10 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 14:03:10 localhost volumio[1397]: info: Discovery: Getting this device information Dec 15 14:03:10 localhost volumio[1397]: info: CoreCommandRouter::volumioGetState Dec 15 14:03:10 localhost volumio[1397]: info: CorePlayQueue::getTrack 0 Dec 15 14:03:10 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 14:03:10 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 15 14:03:10 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 15 14:03:10 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 15 14:03:10 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 15 14:03:10 localhost sudo[2485]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 15 14:03:10 localhost sudo[2485]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:03:10 localhost sudo[2490]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 15 14:03:10 localhost sudo[2490]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:03:10 localhost sudo[2496]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 15 14:03:10 localhost sudo[2496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:03:10 localhost sudo[2490]: pam_unix(sudo:session): session closed for user root Dec 15 14:03:10 localhost sudo[2506]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 14:03:10 localhost sudo[2501]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 15 14:03:10 localhost sudo[2501]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:03:10 localhost sudo[2506]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:03:10 localhost sudo[2496]: pam_unix(sudo:session): session closed for user root Dec 15 14:03:10 localhost sudo[2501]: pam_unix(sudo:session): session closed for user root Dec 15 14:03:10 localhost sudo[2485]: pam_unix(sudo:session): session closed for user root Dec 15 14:03:10 localhost sudo[2506]: pam_unix(sudo:session): session closed for user root Dec 15 14:03:10 localhost sudo[2508]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 14:03:10 localhost sudo[2508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:03:10 localhost sudo[2508]: pam_unix(sudo:session): session closed for user root Dec 15 14:03:10 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 14:03:10 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 14:03:10 localhost volumio[1397]: info: Discovery: Getting this device information Dec 15 14:03:10 localhost volumio[1397]: info: CoreCommandRouter::volumioGetState Dec 15 14:03:10 localhost volumio[1397]: info: CorePlayQueue::getTrack 0 Dec 15 14:03:10 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 14:03:10 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 14:03:10 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 14:03:10 localhost volumio[1397]: info: Discovery: Getting this device information Dec 15 14:03:10 localhost volumio[1397]: info: CoreCommandRouter::volumioGetState Dec 15 14:03:10 localhost volumio[1397]: info: CorePlayQueue::getTrack 0 Dec 15 14:03:10 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 14:03:10 localhost volumio[1397]: info: CoreCommandRouter::volumioGetState Dec 15 14:03:10 localhost volumio[1397]: info: CorePlayQueue::getTrack 0 Dec 15 14:03:11 localhost volumio[1397]: verbose: New Socket.io Connection to 192.168.0.65:3000 from 192.168.0.20 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 15 14:03:11 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 15 14:03:11 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 15 14:03:11 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 15 14:03:11 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 15 14:03:12 localhost sudo[2518]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 14:03:12 localhost sudo[2520]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 14:03:12 localhost sudo[2518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:03:12 localhost sudo[2520]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:03:12 localhost sudo[2518]: pam_unix(sudo:session): session closed for user root Dec 15 14:03:12 localhost sudo[2520]: pam_unix(sudo:session): session closed for user root Dec 15 14:03:12 localhost volumio[1397]: verbose: New Socket.io Connection to 192.168.0.65 from 192.168.0.20 UA: Mozilla/5.0 (Linux; Android 16; Pixel 6 Pro Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 15 14:03:12 localhost sudo[2525]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 14:03:12 localhost sudo[2525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:03:12 localhost sudo[2527]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 14:03:12 localhost sudo[2525]: pam_unix(sudo:session): session closed for user root Dec 15 14:03:12 localhost sudo[2527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:03:12 localhost sudo[2527]: pam_unix(sudo:session): session closed for user root Dec 15 14:03:12 localhost volumio[1397]: verbose: New Socket.io Connection to 192.168.0.65 from 192.168.0.20 UA: Mozilla/5.0 (Linux; Android 16; Pixel 6 Pro Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 15 14:03:12 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 15 14:03:12 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 15 14:03:12 localhost volumio[1397]: info: CoreCommandRouter::volumioGetVisibleSources Dec 15 14:03:12 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 14:03:12 localhost volumio[1397]: info: CoreCommandRouter::volumioGetState Dec 15 14:03:12 localhost volumio[1397]: info: CorePlayQueue::getTrack 0 Dec 15 14:03:12 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 15 14:03:12 localhost volumio[1397]: info: CoreCommandRouter::volumioGetQueue Dec 15 14:03:12 localhost volumio[1397]: info: CoreStateMachine::getQueue Dec 15 14:03:12 localhost volumio[1397]: info: CorePlayQueue::getQueue Dec 15 14:03:12 localhost volumio[1397]: info: Listing playlists Dec 15 14:03:12 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 15 14:03:12 localhost volumio[1397]: info: Received Get System Info Dec 15 14:03:12 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 14:03:12 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 14:03:12 localhost volumio[1397]: info: Discovery: Getting this device information Dec 15 14:03:12 localhost volumio[1397]: info: CoreCommandRouter::volumioGetState Dec 15 14:03:12 localhost volumio[1397]: info: CorePlayQueue::getTrack 0 Dec 15 14:03:12 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 14:03:12 localhost volumio[1397]: info: CoreCommandRouter::volumioGetState Dec 15 14:03:12 localhost volumio[1397]: info: CorePlayQueue::getTrack 0 Dec 15 14:03:12 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 15 14:03:12 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 15 14:03:14 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 15 14:03:14 localhost volumio[1397]: info: Received Get System Info Dec 15 14:03:14 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 14:03:14 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 14:03:14 localhost volumio[1397]: info: Discovery: Getting this device information Dec 15 14:03:14 localhost volumio[1397]: info: CoreCommandRouter::volumioGetState Dec 15 14:03:14 localhost volumio[1397]: info: CorePlayQueue::getTrack 0 Dec 15 14:03:14 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 14:03:14 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 15 14:03:22 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 15 14:03:23 localhost volumio[1397]: info: CoreCommandRouter::volumioPlay Dec 15 14:03:23 localhost volumio[1397]: info: CoreStateMachine::play index undefined Dec 15 14:03:23 localhost volumio[1397]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 14:03:23 localhost volumio[1397]: info: CorePlayQueue::getTrack 0 Dec 15 14:03:23 localhost volumio[1397]: info: CoreStateMachine::startPlaybackTimer Dec 15 14:03:23 localhost volumio[1397]: info: CorePlayQueue::getTrack 0 Dec 15 14:03:23 localhost volumio[1397]: info: [1765803803015] ControllerWebradio::clearAddPlayTrack Dec 15 14:03:23 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand stop Dec 15 14:03:23 localhost volumio[1397]: info: sendMpdCommand stop took 4 milliseconds Dec 15 14:03:23 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand clear Dec 15 14:03:23 localhost volumio[1397]: info: sendMpdCommand clear took 2 milliseconds Dec 15 14:03:23 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand load "https://stream.zeno.fm/00rt0rdm7k8uv" Dec 15 14:03:23 localhost volumio[1397]: info: Dec 15 14:03:23 localhost volumio[1397]: ---------------------------- MPD announces system playlist update Dec 15 14:03:23 localhost volumio[1397]: info: Ignoring MPD Status Update Dec 15 14:03:23 localhost volumio[1397]: info: Dec 15 14:03:23 localhost volumio[1397]: ---------------------------- MPD announces system playlist update Dec 15 14:03:23 localhost volumio[1397]: info: Ignoring MPD Status Update Dec 15 14:03:23 localhost volumio[1397]: info: Dec 15 14:03:23 localhost volumio[1397]: ---------------------------- MPD announces system playlist update Dec 15 14:03:23 localhost volumio[1397]: info: Ignoring MPD Status Update Dec 15 14:03:26 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand add "https://stream.zeno.fm/00rt0rdm7k8uv" Dec 15 14:03:26 localhost volumio[1397]: error: updateQueue error: null Dec 15 14:03:26 localhost volumio[1397]: error: updateQueue error: null Dec 15 14:03:26 localhost volumio[1397]: error: updateQueue error: null Dec 15 14:03:26 localhost volumio[1397]: info: ------------------------------ 3644ms Dec 15 14:03:26 localhost volumio[1397]: info: ------------------------------ 3636ms Dec 15 14:03:26 localhost volumio[1397]: info: ------------------------------ 3634ms Dec 15 14:03:26 localhost volumio[1397]: info: Dec 15 14:03:26 localhost volumio[1397]: ---------------------------- MPD announces system playlist update Dec 15 14:03:26 localhost volumio[1397]: info: Ignoring MPD Status Update Dec 15 14:03:26 localhost volumio[1397]: info: sendMpdCommand add "https://stream.zeno.fm/00rt0rdm7k8uv" took 6 milliseconds Dec 15 14:03:26 localhost volumio[1397]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 15 14:03:26 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand play Dec 15 14:03:26 localhost volumio[1397]: info: Dec 15 14:03:26 localhost volumio[1397]: ---------------------------- MPD announces system playlist update Dec 15 14:03:26 localhost volumio[1397]: info: Ignoring MPD Status Update Dec 15 14:03:26 localhost volumio[1397]: info: Dec 15 14:03:26 localhost volumio[1397]: ---------------------------- MPD announces system playlist update Dec 15 14:03:26 localhost volumio[1397]: info: Ignoring MPD Status Update Dec 15 14:03:26 localhost volumio[1397]: info: ------------------------------ 11ms Dec 15 14:03:26 localhost volumio[1397]: info: sendMpdCommand play took 8 milliseconds Dec 15 14:03:26 localhost volumio[1397]: info: ------------------------------ 7ms Dec 15 14:03:26 localhost volumio[1397]: info: ------------------------------ 8ms Dec 15 14:03:28 localhost volumio[1397]: info: Dec 15 14:03:28 localhost volumio[1397]: ---------------------------- MPD announces state update: player Dec 15 14:03:28 localhost volumio[1397]: info: ControllerMpd::getState Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand status Dec 15 14:03:28 localhost volumio[1397]: info: Dec 15 14:03:28 localhost volumio[1397]: ---------------------------- MPD announces state update: player Dec 15 14:03:28 localhost volumio[1397]: info: ControllerMpd::getState Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand status Dec 15 14:03:28 localhost volumio[1397]: info: Dec 15 14:03:28 localhost volumio[1397]: ---------------------------- MPD announces state update: player Dec 15 14:03:28 localhost volumio[1397]: info: Dec 15 14:03:28 localhost volumio[1397]: ---------------------------- MPD announces state update: player Dec 15 14:03:28 localhost volumio[1397]: info: ControllerMpd::getState Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand status Dec 15 14:03:28 localhost volumio[1397]: info: sendMpdCommand status took 7 milliseconds Dec 15 14:03:28 localhost volumio[1397]: info: ControllerMpd::getState Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand status Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::parseState Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 14:03:28 localhost volumio[1397]: info: Dec 15 14:03:28 localhost volumio[1397]: ---------------------------- MPD announces state update: player Dec 15 14:03:28 localhost volumio[1397]: info: ControllerMpd::getState Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand status Dec 15 14:03:28 localhost volumio[1397]: info: Dec 15 14:03:28 localhost volumio[1397]: ---------------------------- MPD announces state update: player Dec 15 14:03:28 localhost volumio[1397]: info: ControllerMpd::getState Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand status Dec 15 14:03:28 localhost volumio[1397]: info: Dec 15 14:03:28 localhost volumio[1397]: ---------------------------- MPD announces system playlist update Dec 15 14:03:28 localhost volumio[1397]: info: Ignoring MPD Status Update Dec 15 14:03:28 localhost volumio[1397]: info: Dec 15 14:03:28 localhost volumio[1397]: ---------------------------- MPD announces state update: player Dec 15 14:03:28 localhost volumio[1397]: info: sendMpdCommand status took 16 milliseconds Dec 15 14:03:28 localhost volumio[1397]: info: sendMpdCommand status took 13 milliseconds Dec 15 14:03:28 localhost volumio[1397]: info: sendMpdCommand status took 12 milliseconds Dec 15 14:03:28 localhost volumio[1397]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 15 14:03:28 localhost volumio[1397]: info: sendMpdCommand status took 7 milliseconds Dec 15 14:03:28 localhost volumio[1397]: info: ControllerMpd::getState Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand status Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::parseState Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::parseState Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::parseState Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::parseTrackInfo Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::parseState Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 14:03:28 localhost volumio[1397]: info: ControllerMpd::pushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::servicePushState Dec 15 14:03:28 localhost volumio[1397]: info: CorePlayQueue::getTrack 0 Dec 15 14:03:28 localhost volumio[1397]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"00rt0rdm7k8uv","artist":null,"album":null,"uri":"https://stream.zeno.fm/00rt0rdm7k8uv","trackType":""} Dec 15 14:03:28 localhost volumio[1397]: verbose: CURRENT POSITION 0 Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::syncState stateService play Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::syncState currentStatus stop Dec 15 14:03:28 localhost volumio[1397]: info: ------------------------------ 39ms Dec 15 14:03:28 localhost volumio[1397]: info: Dec 15 14:03:28 localhost volumio[1397]: ---------------------------- MPD announces system playlist update Dec 15 14:03:28 localhost volumio[1397]: info: Ignoring MPD Status Update Dec 15 14:03:28 localhost volumio[1397]: info: Dec 15 14:03:28 localhost volumio[1397]: ---------------------------- MPD announces state update: player Dec 15 14:03:28 localhost volumio[1397]: info: ControllerMpd::getState Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand status Dec 15 14:03:28 localhost volumio[1397]: info: Dec 15 14:03:28 localhost volumio[1397]: ---------------------------- MPD announces system playlist update Dec 15 14:03:28 localhost volumio[1397]: info: Ignoring MPD Status Update Dec 15 14:03:28 localhost volumio[1397]: info: Dec 15 14:03:28 localhost volumio[1397]: ---------------------------- MPD announces state update: player Dec 15 14:03:28 localhost volumio[1397]: info: ControllerMpd::getState Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand status Dec 15 14:03:28 localhost volumio[1397]: info: sendMpdCommand status took 25 milliseconds Dec 15 14:03:28 localhost volumio[1397]: info: ------------------------------ 24ms Dec 15 14:03:28 localhost volumio[1397]: info: sendMpdCommand status took 21 milliseconds Dec 15 14:03:28 localhost volumio[1397]: info: sendMpdCommand playlistinfo took 20 milliseconds Dec 15 14:03:28 localhost volumio[1397]: info: sendMpdCommand playlistinfo took 20 milliseconds Dec 15 14:03:28 localhost volumio[1397]: info: sendMpdCommand playlistinfo took 20 milliseconds Dec 15 14:03:28 localhost volumio[1397]: info: sendMpdCommand playlistinfo took 16 milliseconds Dec 15 14:03:28 localhost volumio[1397]: info: ------------------------------ 8ms Dec 15 14:03:28 localhost volumio[1397]: info: sendMpdCommand status took 8 milliseconds Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::parseState Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::parseState Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::parseTrackInfo Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::parseTrackInfo Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::parseTrackInfo Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::parseTrackInfo Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::parseState Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 14:03:28 localhost volumio[1397]: info: ControllerMpd::pushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::servicePushState Dec 15 14:03:28 localhost volumio[1397]: info: CorePlayQueue::getTrack 0 Dec 15 14:03:28 localhost volumio[1397]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bob Baldwin - Silent Night / The Calm","artist":"Smooth Jazz Instrumental","album":null,"uri":"https://stream.zeno.fm/00rt0rdm7k8uv","trackType":""} Dec 15 14:03:28 localhost volumio[1397]: verbose: CURRENT POSITION 0 Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::syncState stateService play Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::syncState currentStatus play Dec 15 14:03:28 localhost volumio[1397]: info: Received an update from plugin. extracting info from payload Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::pushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::volumioPushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::pushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::volumioPushState Dec 15 14:03:28 localhost volumio[1397]: info: ControllerMpd::pushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::servicePushState Dec 15 14:03:28 localhost volumio[1397]: info: CorePlayQueue::getTrack 0 Dec 15 14:03:28 localhost volumio[1397]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bob Baldwin - Silent Night / The Calm","artist":"Smooth Jazz Instrumental","album":null,"uri":"https://stream.zeno.fm/00rt0rdm7k8uv","trackType":""} Dec 15 14:03:28 localhost volumio[1397]: verbose: CURRENT POSITION 0 Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::syncState stateService play Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::syncState currentStatus play Dec 15 14:03:28 localhost volumio[1397]: info: Received an update from plugin. extracting info from payload Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::pushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::volumioPushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::pushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::volumioPushState Dec 15 14:03:28 localhost volumio[1397]: info: ControllerMpd::pushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::servicePushState Dec 15 14:03:28 localhost volumio[1397]: info: CorePlayQueue::getTrack 0 Dec 15 14:03:28 localhost volumio[1397]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bob Baldwin - Silent Night / The Calm","artist":"Smooth Jazz Instrumental","album":null,"uri":"https://stream.zeno.fm/00rt0rdm7k8uv","trackType":""} Dec 15 14:03:28 localhost volumio[1397]: verbose: CURRENT POSITION 0 Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::syncState stateService play Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::syncState currentStatus play Dec 15 14:03:28 localhost volumio[1397]: info: Received an update from plugin. extracting info from payload Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::pushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::volumioPushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::pushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::volumioPushState Dec 15 14:03:28 localhost volumio[1397]: info: ControllerMpd::pushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::servicePushState Dec 15 14:03:28 localhost volumio[1397]: info: CorePlayQueue::getTrack 0 Dec 15 14:03:28 localhost volumio[1397]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Bob Baldwin - Silent Night / The Calm","artist":"Smooth Jazz Instrumental","album":null,"uri":"https://stream.zeno.fm/00rt0rdm7k8uv","trackType":""} Dec 15 14:03:28 localhost volumio[1397]: verbose: CURRENT POSITION 0 Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::syncState stateService play Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::syncState currentStatus play Dec 15 14:03:28 localhost volumio[1397]: info: Received an update from plugin. extracting info from payload Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::pushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::volumioPushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::pushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::volumioPushState Dec 15 14:03:28 localhost volumio[1397]: info: ------------------------------ 151ms Dec 15 14:03:28 localhost volumio[1397]: info: ------------------------------ 149ms Dec 15 14:03:28 localhost volumio[1397]: info: ------------------------------ 149ms Dec 15 14:03:28 localhost volumio[1397]: info: ------------------------------ 141ms Dec 15 14:03:28 localhost volumio[1397]: info: ------------------------------ 120ms Dec 15 14:03:28 localhost volumio[1397]: info: sendMpdCommand status took 119 milliseconds Dec 15 14:03:28 localhost volumio[1397]: info: sendMpdCommand playlistinfo took 113 milliseconds Dec 15 14:03:28 localhost volumio[1397]: info: sendMpdCommand playlistinfo took 114 milliseconds Dec 15 14:03:28 localhost volumio[1397]: info: sendMpdCommand playlistinfo took 112 milliseconds Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::parseState Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::parseTrackInfo Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::parseTrackInfo Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::parseTrackInfo Dec 15 14:03:28 localhost volumio[1397]: info: ControllerMpd::pushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::servicePushState Dec 15 14:03:28 localhost volumio[1397]: info: CorePlayQueue::getTrack 0 Dec 15 14:03:28 localhost volumio[1397]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Bob Baldwin - Silent Night / The Calm","artist":"Smooth Jazz Instrumental","album":null,"uri":"https://stream.zeno.fm/00rt0rdm7k8uv","trackType":""} Dec 15 14:03:28 localhost volumio[1397]: verbose: CURRENT POSITION 0 Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::syncState stateService play Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::syncState currentStatus play Dec 15 14:03:28 localhost volumio[1397]: info: Received an update from plugin. extracting info from payload Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::pushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::volumioPushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::pushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::volumioPushState Dec 15 14:03:28 localhost volumio[1397]: info: ControllerMpd::pushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::servicePushState Dec 15 14:03:28 localhost volumio[1397]: info: CorePlayQueue::getTrack 0 Dec 15 14:03:28 localhost volumio[1397]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Bob Baldwin - Silent Night / The Calm","artist":"Smooth Jazz Instrumental","album":null,"uri":"https://stream.zeno.fm/00rt0rdm7k8uv","trackType":""} Dec 15 14:03:28 localhost volumio[1397]: verbose: CURRENT POSITION 0 Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::syncState stateService play Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::syncState currentStatus play Dec 15 14:03:28 localhost volumio[1397]: info: Received an update from plugin. extracting info from payload Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::pushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::volumioPushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::pushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::volumioPushState Dec 15 14:03:28 localhost volumio[1397]: info: ControllerMpd::pushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::servicePushState Dec 15 14:03:28 localhost volumio[1397]: info: CorePlayQueue::getTrack 0 Dec 15 14:03:28 localhost volumio[1397]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Bob Baldwin - Silent Night / The Calm","artist":"Smooth Jazz Instrumental","album":null,"uri":"https://stream.zeno.fm/00rt0rdm7k8uv","trackType":""} Dec 15 14:03:28 localhost volumio[1397]: verbose: CURRENT POSITION 0 Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::syncState stateService play Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::syncState currentStatus play Dec 15 14:03:28 localhost volumio[1397]: info: Received an update from plugin. extracting info from payload Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::pushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::volumioPushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::pushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::volumioPushState Dec 15 14:03:28 localhost volumio[1397]: info: ------------------------------ 247ms Dec 15 14:03:28 localhost volumio[1397]: info: ------------------------------ 246ms Dec 15 14:03:28 localhost volumio[1397]: info: ------------------------------ 230ms Dec 15 14:03:28 localhost volumio[1397]: info: sendMpdCommand playlistinfo took 183 milliseconds Dec 15 14:03:28 localhost volumio[1397]: verbose: ControllerMpd::parseTrackInfo Dec 15 14:03:28 localhost volumio[1397]: info: ControllerMpd::pushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::servicePushState Dec 15 14:03:28 localhost volumio[1397]: info: CorePlayQueue::getTrack 0 Dec 15 14:03:28 localhost volumio[1397]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Bob Baldwin - Silent Night / The Calm","artist":"Smooth Jazz Instrumental","album":null,"uri":"https://stream.zeno.fm/00rt0rdm7k8uv","trackType":""} Dec 15 14:03:28 localhost volumio[1397]: verbose: CURRENT POSITION 0 Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::syncState stateService play Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::syncState currentStatus play Dec 15 14:03:28 localhost volumio[1397]: info: Received an update from plugin. extracting info from payload Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::pushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::volumioPushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreStateMachine::pushState Dec 15 14:03:28 localhost volumio[1397]: info: CoreCommandRouter::volumioPushState Dec 15 14:03:28 localhost volumio[1397]: info: ------------------------------ 340ms Dec 15 14:03:30 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 15 14:03:30 localhost volumio[1397]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 15 14:03:30 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 15 14:03:30 localhost volumio[1397]: info: Received Get System Version Dec 15 14:03:30 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 14:03:30 localhost volumio[1397]: info: Received Get System Info Dec 15 14:03:30 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 14:03:30 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 14:03:30 localhost volumio[1397]: info: Discovery: Getting this device information Dec 15 14:03:30 localhost volumio[1397]: info: CoreCommandRouter::volumioGetState Dec 15 14:03:30 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 14:03:31 localhost volumio[1397]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 14:03:31 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 15 14:03:31 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 15 14:03:31 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 15 14:03:31 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Dec 15 14:03:32 localhost volumio[1397]: info: Received Get System Version Dec 15 14:03:32 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 14:03:35 localhost volumio[1397]: info: CALLMETHOD: system_controller system setLanguageTimezone [object Object] Dec 15 14:03:35 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , setLanguageTimezone Dec 15 14:03:35 localhost volumio[1397]: info: Setting timezone to Europe/Berlin Dec 15 14:03:35 localhost sudo[2668]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 15 14:03:35 localhost sudo[2668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:03:36 localhost sudo[2668]: pam_unix(sudo:session): session closed for user root Dec 15 14:03:36 localhost sudo[2672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Berlin /etc/localtime Dec 15 14:03:36 localhost sudo[2672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:03:36 localhost sudo[2672]: pam_unix(sudo:session): session closed for user root Dec 15 14:03:36 localhost sudo[2676]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 15 14:03:36 localhost sudo[2676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:03:36 localhost sudo[2676]: pam_unix(sudo:session): session closed for user root Dec 15 14:03:36 localhost sudo[2680]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Berlin Dec 15 14:03:36 localhost sudo[2680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:03:36 localhost dbus-daemon[833]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.23' (uid=0 pid=2682 comm="/usr/bin/timedatectl set-timezone Europe/Berlin") Dec 15 14:03:36 localhost systemd[1]: Starting systemd-timedated.service - Time & Date Service... Dec 15 14:03:36 localhost dbus-daemon[833]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 15 14:03:36 localhost systemd[1]: Started systemd-timedated.service - Time & Date Service. Dec 15 14:03:36 localhost sudo[2680]: pam_unix(sudo:session): session closed for user root Dec 15 14:03:36 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 15 14:03:36 localhost volumio[1397]: info: Loading i18n strings for locale en Dec 15 14:03:36 localhost volumio[1397]: Updating browse sources language Dec 15 14:03:36 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 14:03:36 localhost volumio[1397]: info: Fetching Streaming Services browse cache Dec 15 14:03:37 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 15 14:03:38 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 15 14:03:57 localhost volumio[1397]: info: Dec 15 14:03:57 localhost volumio[1397]: ---------------------------- MPD announces system playlist update Dec 15 14:03:57 localhost volumio[1397]: info: Ignoring MPD Status Update Dec 15 14:03:57 localhost volumio[1397]: info: Dec 15 14:03:57 localhost volumio[1397]: ---------------------------- MPD announces state update: player Dec 15 14:03:57 localhost volumio[1397]: info: ControllerMpd::getState Dec 15 14:03:57 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand status Dec 15 14:03:57 localhost volumio[1397]: info: Dec 15 14:03:57 localhost volumio[1397]: ---------------------------- MPD announces system playlist update Dec 15 14:03:57 localhost volumio[1397]: info: Ignoring MPD Status Update Dec 15 14:03:57 localhost volumio[1397]: info: Dec 15 14:03:57 localhost volumio[1397]: ---------------------------- MPD announces state update: player Dec 15 14:03:57 localhost volumio[1397]: info: ControllerMpd::getState Dec 15 14:03:57 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand status Dec 15 14:03:57 localhost volumio[1397]: info: Dec 15 14:03:57 localhost volumio[1397]: ---------------------------- MPD announces system playlist update Dec 15 14:03:57 localhost volumio[1397]: info: Ignoring MPD Status Update Dec 15 14:03:57 localhost volumio[1397]: info: Dec 15 14:03:57 localhost volumio[1397]: ---------------------------- MPD announces state update: player Dec 15 14:03:57 localhost volumio[1397]: info: ControllerMpd::getState Dec 15 14:03:57 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand status Dec 15 14:03:57 localhost volumio[1397]: info: ------------------------------ 5ms Dec 15 14:03:57 localhost volumio[1397]: info: sendMpdCommand status took 4 milliseconds Dec 15 14:03:57 localhost volumio[1397]: verbose: ControllerMpd::parseState Dec 15 14:03:57 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 14:03:57 localhost volumio[1397]: info: ------------------------------ 6ms Dec 15 14:03:57 localhost volumio[1397]: info: sendMpdCommand status took 6 milliseconds Dec 15 14:03:57 localhost volumio[1397]: info: ------------------------------ 6ms Dec 15 14:03:57 localhost volumio[1397]: info: sendMpdCommand status took 4 milliseconds Dec 15 14:03:57 localhost volumio[1397]: verbose: ControllerMpd::parseState Dec 15 14:03:57 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 14:03:57 localhost volumio[1397]: verbose: ControllerMpd::parseState Dec 15 14:03:57 localhost volumio[1397]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 15 14:03:57 localhost volumio[1397]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 15 14:03:57 localhost volumio[1397]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 15 14:03:57 localhost volumio[1397]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 15 14:03:57 localhost volumio[1397]: verbose: ControllerMpd::parseTrackInfo Dec 15 14:03:57 localhost volumio[1397]: verbose: ControllerMpd::parseTrackInfo Dec 15 14:03:57 localhost volumio[1397]: verbose: ControllerMpd::parseTrackInfo Dec 15 14:03:57 localhost volumio[1397]: info: ControllerMpd::pushState Dec 15 14:03:57 localhost volumio[1397]: info: CoreCommandRouter::servicePushState Dec 15 14:03:57 localhost volumio[1397]: info: CorePlayQueue::getTrack 0 Dec 15 14:03:57 localhost volumio[1397]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30362,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Euge Groove - Cafe Del Soul","artist":"Smooth Jazz Instrumental","album":null,"uri":"https://stream.zeno.fm/00rt0rdm7k8uv","trackType":""} Dec 15 14:03:57 localhost volumio[1397]: verbose: CURRENT POSITION 0 Dec 15 14:03:57 localhost volumio[1397]: info: CoreStateMachine::syncState stateService play Dec 15 14:03:57 localhost volumio[1397]: info: CoreStateMachine::syncState currentStatus play Dec 15 14:03:57 localhost volumio[1397]: info: Received an update from plugin. extracting info from payload Dec 15 14:03:57 localhost volumio[1397]: info: CoreStateMachine::pushState Dec 15 14:03:57 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 14:03:57 localhost volumio[1397]: info: CoreCommandRouter::volumioPushState Dec 15 14:03:57 localhost volumio[1397]: info: CoreStateMachine::pushState Dec 15 14:03:57 localhost volumio[1397]: info: CoreCommandRouter::volumioPushState Dec 15 14:03:57 localhost volumio[1397]: info: ControllerMpd::pushState Dec 15 14:03:57 localhost volumio[1397]: info: CoreCommandRouter::servicePushState Dec 15 14:03:57 localhost volumio[1397]: info: CorePlayQueue::getTrack 0 Dec 15 14:03:57 localhost volumio[1397]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30362,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Euge Groove - Cafe Del Soul","artist":"Smooth Jazz Instrumental","album":null,"uri":"https://stream.zeno.fm/00rt0rdm7k8uv","trackType":""} Dec 15 14:03:57 localhost volumio[1397]: verbose: CURRENT POSITION 0 Dec 15 14:03:57 localhost volumio[1397]: info: CoreStateMachine::syncState stateService play Dec 15 14:03:57 localhost volumio[1397]: info: CoreStateMachine::syncState currentStatus play Dec 15 14:03:57 localhost volumio[1397]: info: Received an update from plugin. extracting info from payload Dec 15 14:03:57 localhost volumio[1397]: info: CoreStateMachine::pushState Dec 15 14:03:57 localhost volumio[1397]: info: CoreCommandRouter::volumioPushState Dec 15 14:03:58 localhost volumio[1397]: info: CoreStateMachine::pushState Dec 15 14:03:58 localhost volumio[1397]: info: CoreCommandRouter::volumioPushState Dec 15 14:03:58 localhost volumio[1397]: info: ControllerMpd::pushState Dec 15 14:03:58 localhost volumio[1397]: info: CoreCommandRouter::servicePushState Dec 15 14:03:58 localhost volumio[1397]: info: CorePlayQueue::getTrack 0 Dec 15 14:03:58 localhost volumio[1397]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30362,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Euge Groove - Cafe Del Soul","artist":"Smooth Jazz Instrumental","album":null,"uri":"https://stream.zeno.fm/00rt0rdm7k8uv","trackType":""} Dec 15 14:03:58 localhost volumio[1397]: verbose: CURRENT POSITION 0 Dec 15 14:03:58 localhost volumio[1397]: info: CoreStateMachine::syncState stateService play Dec 15 14:03:58 localhost volumio[1397]: info: CoreStateMachine::syncState currentStatus play Dec 15 14:03:58 localhost volumio[1397]: info: Received an update from plugin. extracting info from payload Dec 15 14:03:58 localhost volumio[1397]: info: CoreStateMachine::pushState Dec 15 14:03:58 localhost volumio[1397]: info: CoreCommandRouter::volumioPushState Dec 15 14:03:58 localhost volumio[1397]: info: CoreStateMachine::pushState Dec 15 14:03:58 localhost volumio[1397]: info: CoreCommandRouter::volumioPushState Dec 15 14:03:58 localhost volumio[1397]: info: ------------------------------ 112ms Dec 15 14:03:58 localhost volumio[1397]: info: ------------------------------ 111ms Dec 15 14:03:58 localhost volumio[1397]: info: ------------------------------ 110ms Dec 15 14:04:06 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 15 14:04:06 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 15 14:04:06 localhost systemd[1]: systemd-timedated.service: Deactivated successfully. Dec 15 14:04:06 localhost volumio-remote-updater[845]: Test mode disabled Dec 15 14:04:06 localhost volumio-remote-updater[845]: Alpha mode disabled Dec 15 14:04:06 localhost volumio-remote-updater[845]: Alpha legacy test mode disabled Dec 15 14:04:06 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 15 14:04:06 localhost volumio[1397]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 15 14:04:06 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 15 14:04:25 localhost volumio[1397]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 14:04:25 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 15 14:04:25 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 15 14:04:25 localhost sudo[2918]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 15 14:04:25 localhost sudo[2918]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:04:25 localhost sudo[2918]: pam_unix(sudo:session): session closed for user root Dec 15 14:04:25 localhost sudo[2923]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 15 14:04:25 localhost sudo[2923]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:04:25 localhost sudo[2923]: pam_unix(sudo:session): session closed for user root Dec 15 14:04:25 localhost sudo[2929]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 15 14:04:25 localhost sudo[2929]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:04:25 localhost sudo[2934]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 15 14:04:25 localhost sudo[2929]: pam_unix(sudo:session): session closed for user root Dec 15 14:04:25 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 15 14:04:25 localhost sudo[2934]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:04:25 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 15 14:04:25 localhost sudo[2934]: pam_unix(sudo:session): session closed for user root Dec 15 14:04:25 localhost sudo[2941]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 14:04:25 localhost sudo[2941]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:04:25 localhost sudo[2941]: pam_unix(sudo:session): session closed for user root Dec 15 14:04:25 localhost sudo[2945]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 14:04:25 localhost sudo[2945]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:04:25 localhost sudo[2945]: pam_unix(sudo:session): session closed for user root Dec 15 14:04:25 localhost sudo[2948]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 15 14:04:25 localhost sudo[2948]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:04:30 localhost sudo[2948]: pam_unix(sudo:session): session closed for user root Dec 15 14:04:46 localhost volumio[1397]: info: CALLMETHOD: system_controller network saveHotspotSettings [object Object] Dec 15 14:04:46 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Dec 15 14:04:47 localhost sudo[3050]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Dec 15 14:04:47 localhost sudo[3050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:04:47 localhost sudo[3050]: pam_unix(sudo:session): session closed for user root Dec 15 14:04:47 localhost volumio[1397]: info: Permissions for /etc/hostapd/hostapd.conf Dec 15 14:04:47 localhost volumio[1397]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Dec 15 14:04:47 localhost volumio[1397]: info: Discovery: Restarting Advertising due to device name change Dec 15 14:04:47 localhost sudo[3053]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Dec 15 14:04:47 localhost sudo[3053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:04:47 localhost systemd[1]: Stopping wireless.service - Wireless Services... Dec 15 14:04:47 localhost systemd[1]: wireless.service: Killing process 1258 (wpa_supplicant) with signal SIGKILL. Dec 15 14:04:47 localhost systemd[1]: wireless.service: Deactivated successfully. Dec 15 14:04:47 localhost dhcpcd[917]: wlan0: carrier lost Dec 15 14:04:47 localhost kernel: wlan0: deauthenticating from b8:a5:35:38:59:00 by local choice (Reason: 3=DEAUTH_LEAVING) Dec 15 14:04:47 localhost systemd[1]: Stopped wireless.service - Wireless Services. Dec 15 14:04:47 localhost systemd[1]: wireless.service: Consumed 2.876s CPU time. Dec 15 14:04:47 localhost systemd[1]: Starting wireless.service - Wireless Services... Dec 15 14:04:47 localhost avahi-daemon[1392]: Withdrawing address record for 192.168.0.65 on wlan0. Dec 15 14:04:47 localhost avahi-daemon[1392]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.65. Dec 15 14:04:47 localhost dhcpcd[917]: wlan0: deleting route to 192.168.0.0/24 Dec 15 14:04:47 localhost dhcpcd[917]: wlan0: deleting default route via 192.168.0.1 Dec 15 14:04:47 localhost avahi-daemon[1392]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 15 14:04:47 localhost volumio[1397]: info: Discovery: A device disappeared from network Dec 15 14:04:47 localhost volumio[1397]: info: Discovery: Device volumio3 disappeared from network Dec 15 14:04:47 localhost systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 15 14:04:47 localhost systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 15 14:04:47 localhost systemd[1]: welcome.service: Deactivated successfully. Dec 15 14:04:47 localhost volumio[1397]: info: Discovery: Disconnected from remote: 192.168.0.229 Dec 15 14:04:47 localhost systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 15 14:04:47 localhost systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 15 14:04:47 localhost volumio[1397]: info: Discovery: A device disappeared from network Dec 15 14:04:47 localhost systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 15 14:04:47 localhost welcome[3087]: Resolved ip:[0] Dec 15 14:04:47 localhost systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 15 14:04:47 localhost systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 15 14:04:47 localhost wireless.js[3057]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Dec 15 14:04:47 localhost wireless.js[3057]: WIRELESS.JS: Wireless.js initializing wireless flow Dec 15 14:04:47 localhost wireless.js[3057]: WIRELESS.JS: Cleaning previous... Dec 15 14:04:47 localhost sudo[3106]: root : unable to resolve host localhost: System error Dec 15 14:04:47 localhost sudo[3106]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 15 14:04:47 localhost sudo[3106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 15 14:04:47 localhost sudo[3106]: pam_unix(sudo:session): session closed for user root Dec 15 14:04:47 localhost sudo[3108]: root : unable to resolve host localhost: System error Dec 15 14:04:47 localhost sudo[3108]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 15 14:04:47 localhost sudo[3108]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 15 14:04:47 localhost sudo[3108]: pam_unix(sudo:session): session closed for user root Dec 15 14:04:47 localhost wireless.js[3057]: WIRELESS.JS: Stopped aP Dec 15 14:04:47 localhost sudo[3117]: volumio : unable to resolve host localhost: System error Dec 15 14:04:47 localhost sudo[3117]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 15 14:04:47 localhost sudo[3117]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:04:47 localhost sudo[3117]: pam_unix(sudo:session): session closed for user root Dec 15 14:04:47 localhost sudo[3119]: volumio : unable to resolve host localhost: System error Dec 15 14:04:47 localhost sudo[3119]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 15 14:04:47 localhost sudo[3119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:04:47 localhost sudo[3119]: pam_unix(sudo:session): session closed for user root Dec 15 14:04:47 localhost wireless.js[3057]: sudo: unable to resolve host localhost: System error Dec 15 14:04:47 localhost wireless.js[3057]: sudo: unable to resolve host localhost: System error Dec 15 14:04:47 localhost sudo[3127]: volumio : unable to resolve host localhost: System error Dec 15 14:04:47 localhost sudo[3127]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 15 14:04:47 localhost sudo[3127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:04:47 localhost sudo[3127]: pam_unix(sudo:session): session closed for user root Dec 15 14:04:47 localhost sudo[3129]: volumio : unable to resolve host localhost: System error Dec 15 14:04:47 localhost sudo[3129]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 15 14:04:47 localhost sudo[3129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:04:48 localhost volumio[1397]: info: Discovery: A device disappeared from network Dec 15 14:04:48 localhost sudo[3129]: pam_unix(sudo:session): session closed for user root Dec 15 14:04:48 localhost wireless.js[3057]: sudo: unable to resolve host localhost: System error Dec 15 14:04:48 localhost wireless.js[3057]: sudo: unable to resolve host localhost: System error Dec 15 14:04:48 localhost wireless.js[3057]: WIRELESS.JS: Start wireless flow Dec 15 14:04:48 localhost wireless.js[3057]: WIRELESS.JS: Stopped hotspot (if there).. Dec 15 14:04:48 localhost sudo[3140]: root : unable to resolve host localhost: System error Dec 15 14:04:48 localhost sudo[3140]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 15 14:04:48 localhost sudo[3140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 15 14:04:48 localhost sudo[3140]: pam_unix(sudo:session): session closed for user root Dec 15 14:04:48 localhost sudo[3142]: root : unable to resolve host localhost: System error Dec 15 14:04:48 localhost sudo[3142]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 15 14:04:48 localhost sudo[3142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 15 14:04:48 localhost sudo[3142]: pam_unix(sudo:session): session closed for user root Dec 15 14:04:49 localhost wireless.js[3057]: WIRELESS.JS: DHCP IP fallback Dec 15 14:04:49 localhost wpa_supplicant[3147]: Successfully initialized wpa_supplicant Dec 15 14:04:49 localhost wireless.js[3057]: WIRELESS.JS: Start ap Dec 15 14:04:49 localhost sudo[3148]: root : unable to resolve host localhost: System error Dec 15 14:04:49 localhost sudo[3148]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Dec 15 14:04:49 localhost sudo[3148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 15 14:04:49 localhost dhcpcd[917]: ps_ctl_dispatch: cannot handle another client Dec 15 14:04:49 localhost dhcpcd[917]: control_free: No such file or directory Dec 15 14:04:49 localhost sudo[3148]: pam_unix(sudo:session): session closed for user root Dec 15 14:04:50 localhost wireless.js[3057]: WIRELESS.JS: trying... Dec 15 14:04:50 localhost wpa_supplicant[3150]: wlan0: SME: Trying to authenticate with b8:a5:35:38:59:00 (SSID='Vodafone-58FC' freq=2437 MHz) Dec 15 14:04:50 localhost kernel: wlan0: authenticate with b8:a5:35:38:59:00 (local address=8c:86:dd:50:6e:ed) Dec 15 14:04:50 localhost kernel: wlan0: send auth to b8:a5:35:38:59:00 (try 1/3) Dec 15 14:04:50 localhost kernel: wlan0: authenticated Dec 15 14:04:50 localhost wpa_supplicant[3150]: wlan0: Trying to associate with b8:a5:35:38:59:00 (SSID='Vodafone-58FC' freq=2437 MHz) Dec 15 14:04:50 localhost kernel: wlan0: associate with b8:a5:35:38:59:00 (try 1/3) Dec 15 14:04:50 localhost kernel: wlan0: RX AssocResp from b8:a5:35:38:59:00 (capab=0x1411 status=0 aid=39) Dec 15 14:04:50 localhost wpa_supplicant[3150]: wlan0: Associated with b8:a5:35:38:59:00 Dec 15 14:04:50 localhost wpa_supplicant[3150]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 15 14:04:50 localhost kernel: usb 1-2: rtl8xxxu_bss_info_changed: HT supported Dec 15 14:04:50 localhost kernel: wlan0: associated Dec 15 14:04:50 localhost sudo[3156]: volumio : unable to resolve host localhost: System error Dec 15 14:04:50 localhost sudo[3156]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 15 14:04:50 localhost sudo[3156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:04:50 localhost sudo[3156]: pam_unix(sudo:session): session closed for user root Dec 15 14:04:50 localhost wireless.js[3057]: sudo: unable to resolve host localhost: System error Dec 15 14:04:50 localhost wpa_supplicant[3150]: wlan0: WPA: Key negotiation completed with b8:a5:35:38:59:00 [PTK=CCMP GTK=CCMP] Dec 15 14:04:50 localhost wpa_supplicant[3150]: wlan0: CTRL-EVENT-CONNECTED - Connection to b8:a5:35:38:59:00 completed [id=0 id_str=] Dec 15 14:04:50 localhost dhcpcd[917]: wlan0: carrier acquired Dec 15 14:04:50 localhost dhcpcd[917]: wlan0: IAID dd:50:6e:ed Dec 15 14:04:51 localhost wireless.js[3057]: WIRELESS.JS: trying... Dec 15 14:04:51 localhost dhcpcd[917]: wlan0: soliciting an IPv6 router Dec 15 14:04:51 localhost sudo[3164]: volumio : unable to resolve host localhost: System error Dec 15 14:04:51 localhost sudo[3164]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 15 14:04:51 localhost sudo[3164]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:04:51 localhost sudo[3164]: pam_unix(sudo:session): session closed for user root Dec 15 14:04:51 localhost wireless.js[3057]: sudo: unable to resolve host localhost: System error Dec 15 14:04:51 localhost ntpd[1040]: IO: Deleting interface #3 wlan0, 192.168.0.65#123, interface stats: received=183, sent=183, dropped=2, active_time=254 secs Dec 15 14:04:51 localhost ntpd[1040]: PROTO: 172.104.149.161 unlink local addr 192.168.0.65 -> Dec 15 14:04:51 localhost ntpd[1040]: PROTO: 46.41.1.167 unlink local addr 192.168.0.65 -> Dec 15 14:04:51 localhost ntpd[1040]: PROTO: 144.91.126.59 unlink local addr 192.168.0.65 -> Dec 15 14:04:51 localhost ntpd[1040]: PROTO: 85.214.133.14 unlink local addr 192.168.0.65 -> Dec 15 14:04:51 localhost ntpd[1040]: PROTO: 31.209.85.243 unlink local addr 192.168.0.65 -> Dec 15 14:04:51 localhost ntpd[1040]: PROTO: 94.16.122.152 unlink local addr 192.168.0.65 -> Dec 15 14:04:51 localhost ntpd[1040]: PROTO: 49.12.125.53 unlink local addr 192.168.0.65 -> Dec 15 14:04:51 localhost ntpd[1040]: PROTO: 45.9.61.155 unlink local addr 192.168.0.65 -> Dec 15 14:04:51 localhost ntpd[1040]: PROTO: 88.99.86.9 unlink local addr 192.168.0.65 -> Dec 15 14:04:51 localhost ntpd[1040]: PROTO: 134.60.111.110 unlink local addr 192.168.0.65 -> Dec 15 14:04:51 localhost ntpd[1040]: PROTO: 93.241.86.156 unlink local addr 192.168.0.65 -> Dec 15 14:04:51 localhost ntpd[1040]: PROTO: 162.159.200.123 unlink local addr 192.168.0.65 -> Dec 15 14:04:51 localhost ntpd[1040]: PROTO: 90.187.112.137 unlink local addr 192.168.0.65 -> Dec 15 14:04:51 localhost ntpd[1040]: PROTO: 78.46.56.170 unlink local addr 192.168.0.65 -> Dec 15 14:04:51 localhost ntpd[1040]: PROTO: 129.70.132.37 unlink local addr 192.168.0.65 -> Dec 15 14:04:51 localhost ntpd[1040]: PROTO: 85.220.190.246 unlink local addr 192.168.0.65 -> Dec 15 14:04:51 localhost dhcpcd[917]: wlan0: rebinding lease of 192.168.0.65 Dec 15 14:04:51 localhost dhcpcd[917]: wlan0: probing address 192.168.0.65/24 Dec 15 14:04:52 localhost wireless.js[3057]: WIRELESS.JS: trying... Dec 15 14:04:52 localhost sudo[3173]: volumio : unable to resolve host localhost: System error Dec 15 14:04:52 localhost sudo[3173]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 15 14:04:52 localhost sudo[3173]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:04:52 localhost sudo[3173]: pam_unix(sudo:session): session closed for user root Dec 15 14:04:52 localhost wireless.js[3057]: sudo: unable to resolve host localhost: System error Dec 15 14:04:52 localhost volumio[1397]: info: Discovery: Started advertising with name: Volumio2 Dec 15 14:04:52 localhost volumio[1397]: compat.c: read() failed: Resource temporarily unavailable Dec 15 14:04:52 localhost volumio[1397]: info: Discovery: mDNS temporarily unavailable during network transition Dec 15 14:04:53 localhost wireless.js[3057]: WIRELESS.JS: trying... Dec 15 14:04:53 localhost sudo[3181]: volumio : unable to resolve host localhost: System error Dec 15 14:04:53 localhost sudo[3181]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 15 14:04:53 localhost sudo[3181]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:04:53 localhost sudo[3181]: pam_unix(sudo:session): session closed for user root Dec 15 14:04:53 localhost wireless.js[3057]: sudo: unable to resolve host localhost: System error Dec 15 14:04:53 localhost volumio[1397]: info: Volumio Network Manager: Network status updated: 0 Dec 15 14:04:54 localhost wireless.js[3057]: WIRELESS.JS: trying... Dec 15 14:04:54 localhost sudo[3202]: volumio : unable to resolve host localhost: System error Dec 15 14:04:54 localhost sudo[3202]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 15 14:04:54 localhost sudo[3202]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:04:54 localhost sudo[3202]: pam_unix(sudo:session): session closed for user root Dec 15 14:04:54 localhost wireless.js[3057]: sudo: unable to resolve host localhost: System error Dec 15 14:04:55 localhost wireless.js[3057]: WIRELESS.JS: trying... Dec 15 14:04:55 localhost sudo[3209]: volumio : unable to resolve host localhost: System error Dec 15 14:04:55 localhost sudo[3209]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 15 14:04:55 localhost sudo[3209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:04:55 localhost sudo[3209]: pam_unix(sudo:session): session closed for user root Dec 15 14:04:55 localhost wireless.js[3057]: sudo: unable to resolve host localhost: System error Dec 15 14:04:56 localhost wireless.js[3057]: WIRELESS.JS: trying... Dec 15 14:04:56 localhost sudo[3219]: volumio : unable to resolve host localhost: System error Dec 15 14:04:56 localhost sudo[3219]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 15 14:04:56 localhost sudo[3219]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 14:04:56 localhost sudo[3219]: pam_unix(sudo:session): session closed for user root Dec 15 14:04:56 localhost wireless.js[3057]: sudo: unable to resolve host localhost: System error Dec 15 14:04:56 localhost dhcpcd[917]: wlan0: leased 192.168.0.65 for 604800 seconds Dec 15 14:04:56 localhost avahi-daemon[1392]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.65. Dec 15 14:04:56 localhost avahi-daemon[1392]: New relevant interface wlan0.IPv4 for mDNS. Dec 15 14:04:56 localhost avahi-daemon[1392]: Registering new address record for 192.168.0.65 on wlan0.IPv4. Dec 15 14:04:56 localhost dhcpcd[917]: wlan0: adding route to 192.168.0.0/24 Dec 15 14:04:56 localhost dhcpcd[917]: wlan0: adding default route via 192.168.0.1 Dec 15 14:04:56 localhost systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 15 14:04:56 localhost systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 15 14:04:56 localhost systemd[1]: welcome.service: Deactivated successfully. Dec 15 14:04:56 localhost systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 15 14:04:56 localhost systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 15 14:04:56 localhost systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 15 14:04:56 localhost welcome[3232]: Resolved ip:[1] 192.168.0.65 Dec 15 14:04:56 localhost systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 15 14:04:56 localhost systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 15 14:04:56 localhost volumio[1397]: compat.c: read() failed: Resource temporarily unavailable Dec 15 14:04:56 localhost volumio[1397]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 15 14:04:56 localhost volumio[1397]: Error: dns service error: unknown Dec 15 14:04:56 localhost volumio[1397]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 15 14:04:56 localhost volumio[1397]: errorCode: -65537 Dec 15 14:04:56 localhost volumio[1397]: } Dec 15 14:04:56 localhost volumio[1397]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 15 14:04:56 localhost sudo[3261]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-15 14:03' Dec 15 14:04:56 localhost sudo[3261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"