-- Logs begin at Thu 2019-02-14 23:11:58 NZDT, end at Sun 2025-05-18 10:17:34 NZST. -- May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 18 10:16:00 volumio volumio[1020]: info: Adding plugin bluetooth to MyMusic Plugins May 18 10:16:00 volumio volumio[1020]: info: Adding plugin multiroom to MyMusic Plugins May 18 10:16:00 volumio volumio[1020]: info: Adding plugin metavolumio to MyMusic Plugins May 18 10:16:00 volumio volumio[1020]: info: Adding plugin cd_controller to MyMusic Plugins May 18 10:16:00 volumio volumio[1020]: info: Adding plugin qobuzconnect to MyMusic Plugins May 18 10:16:00 volumio volumio[1020]: info: Adding plugin smart_inputs to MyMusic Plugins May 18 10:16:00 volumio volumio[1020]: info: Adding plugin tidalconnect to MyMusic Plugins May 18 10:16:00 volumio volumio[1020]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 18 10:16:00 volumio volumio[1322]: ....................++++ May 18 10:16:00 volumio volumio[1322]: e is 65537 (0x010001) May 18 10:16:00 volumio volumio[1322]: writing RSA key May 18 10:16:01 volumio volumio[1020]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 18 10:16:01 volumio volumio[1020]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 18 10:16:01 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 10:16:01 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 10:16:01 volumio volumio[1020]: info: Starting MyVolumio Remote Streaming Endpoints May 18 10:16:01 volumio volumio[1020]: info: MyVolumio login type: Token May 18 10:16:01 volumio volumio[1020]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 18 10:16:01 volumio volumio[1020]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 18 10:16:02 volumio volumio[1020]: info: Starting Streaming Service Transparent Proxy May 18 10:16:02 volumio volumio[1020]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 18 10:16:02 volumio volumio[1020]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 18 10:16:02 volumio volumio[1020]: info: Streaming services startup May 18 10:16:02 volumio volumio[1020]: info: Starting Streaming Daemon May 18 10:16:02 volumio sudo[1360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 18 10:16:02 volumio sudo[1360]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:16:02 volumio sudo[1360]: pam_unix(sudo:session): session closed for user root May 18 10:16:02 volumio volumio[1020]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 18 10:16:02 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 18 10:16:02 volumio volumio[1020]: info: CURURI: artists:// May 18 10:16:02 volumio volumio[1020]: info: CoreCommandRouter::volumioGetQueue May 18 10:16:02 volumio volumio[1020]: info: CoreStateMachine::getQueue May 18 10:16:02 volumio volumio[1020]: info: CorePlayQueue::getQueue May 18 10:16:02 volumio volumio[1020]: error: Cannot start Volumio Streaming Daemon May 18 10:16:02 volumio volumio[1020]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 18 10:16:02 volumio volumio[1020]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 18 10:16:03 volumio volumio[1020]: STREAMING PROXY: Starting server on port 3245 May 18 10:16:03 volumio volumio[1020]: Node JS runtime: 14 May 18 10:16:03 volumio volumio[1020]: Cannot compose Albumart path May 18 10:16:04 volumio volumio[1020]: info: Preload queue cleared May 18 10:16:08 volumio volumio[1020]: error: MyVolumio Custom Token format not valid, refreshing it May 18 10:16:09 volumio volumio[1020]: info: MyVolumio login type: Token May 18 10:16:11 volumio volumio[1020]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 18 10:16:11 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 18 10:16:11 volumio volumio[1020]: info: CURURI: artists://Being%20Dead May 18 10:16:11 volumio volumio[1020]: info: Preload queue cleared May 18 10:16:11 volumio volumio[1020]: info: Preload queue cleared May 18 10:16:11 volumio volumio[1020]: info: Preloading song: music-library/NAS/Yeoman/Best of 2024 Rock (Mp3 320kbps) [PMEDIA] ⭐️/084. Being Dead - Blanket of my Bone.mp3 May 18 10:16:11 volumio volumio[1020]: info: Exploding uri music-library/NAS/Yeoman/Best of 2024 Rock (Mp3 320kbps) [PMEDIA] ⭐️/084. Being Dead - Blanket of my Bone.mp3 in service mpd May 18 10:16:11 volumio volumio[1020]: info: ALBUMART /albumart?cacheid=35&web=Being%20Dead/EELS/extralarge&path=%2Fmnt%2FNAS%2FYeoman%2FBest%20of%202024%20Rock%20(Mp3%20320kbps)%20%5BPMEDIA%5D%20%E2%AD%90%EF%B8%8F%2F084.%20Being%20Dead%20-%20Blanket%20of%20my%20Bone.mp3&metadata=false May 18 10:16:11 volumio volumio[1020]: info: URI /mnt/NAS/Yeoman/Best of 2024 Rock (Mp3 320kbps) [PMEDIA] ⭐️/084. Being Dead - Blanket of my Bone.mp3 May 18 10:16:12 volumio volumio[1020]: info: MyVolumio token set successfully May 18 10:16:12 volumio volumio[1020]: info: MYVOLUMIO: Adding device May 18 10:16:12 volumio volumio[1020]: info: MYVOLUMIO: Evaluating Server May 18 10:16:12 volumio volumio[1020]: info: MyVolumio status changed May 18 10:16:12 volumio volumio[1020]: info: Streaming services startup May 18 10:16:12 volumio volumio[1020]: info: Starting Streaming Daemon May 18 10:16:12 volumio volumio[1020]: info: Removing browser output: myVolumio user plan is not superstar May 18 10:16:12 volumio volumio[1020]: info: Removing audio output: May 18 10:16:12 volumio volumio[1020]: info: Stoppping Tunnel 1 May 18 10:16:12 volumio sudo[1402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 18 10:16:12 volumio sudo[1402]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:16:12 volumio sudo[1402]: pam_unix(sudo:session): session closed for user root May 18 10:16:12 volumio sudo[1405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 18 10:16:12 volumio sudo[1405]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:16:12 volumio volumio[1020]: error: Cannot start Volumio Streaming Daemon May 18 10:16:12 volumio volumio[1020]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 18 10:16:12 volumio volumio[1020]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 18 10:16:12 volumio sudo[1405]: pam_unix(sudo:session): session closed for user root May 18 10:16:12 volumio volumio[1020]: info: Remote SSH Stopped May 18 10:16:13 volumio volumio[1020]: info: Setting Geolocation for MyVolumio to au1 May 18 10:16:13 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 10:16:13 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 10:16:13 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 10:16:13 volumio volumio[1020]: info: Preload queue cleared May 18 10:16:13 volumio volumio[1020]: info: CoreCommandRouter::volumioReplaceandPlayItems May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::ClearQueue May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::stop May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 10:16:13 volumio volumio[1020]: info: CorePlayQueue::clearPlayQueue May 18 10:16:13 volumio volumio[1020]: info: CorePlayQueue::saveQueue May 18 10:16:13 volumio volumio[1020]: info: CoreCommandRouter::volumioPushQueue May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::addQueueItems May 18 10:16:13 volumio volumio[1020]: info: CorePlayQueue::addQueueItems May 18 10:16:13 volumio volumio[1020]: info: Preload queue cleared May 18 10:16:13 volumio volumio[1020]: info: Adding Item to queue: music-library/NAS/Yeoman/Best of 2024 Rock (Mp3 320kbps) [PMEDIA] ⭐️/084. Being Dead - Blanket of my Bone.mp3 May 18 10:16:13 volumio volumio[1020]: info: Using cached record of: music-library/NAS/Yeoman/Best of 2024 Rock (Mp3 320kbps) [PMEDIA] ⭐️/084. Being Dead - Blanket of my Bone.mp3 May 18 10:16:13 volumio volumio[1020]: info: CoreCommandRouter::volumioPushQueue May 18 10:16:13 volumio volumio[1020]: info: CorePlayQueue::saveQueue May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::updateTrackBlock May 18 10:16:13 volumio volumio[1020]: info: CorePlayQueue::getTrackBlock May 18 10:16:13 volumio volumio[1020]: info: CoreCommandRouter::volumioPlay May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::play index 0 May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::stop May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::play index undefined May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 10:16:13 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::startPlaybackTimer May 18 10:16:13 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::clearAddPlayTracks NAS/Yeoman/Best of 2024 Rock (Mp3 320kbps) [PMEDIA] ⭐️/084. Being Dead - Blanket of my Bone.mp3 May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand stop May 18 10:16:13 volumio volumio[1020]: info: sendMpdCommand stop took 8 milliseconds May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand clear May 18 10:16:13 volumio volumio[1020]: info: sendMpdCommand clear took 0 milliseconds May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand add "NAS/Yeoman/Best of 2024 Rock (Mp3 320kbps) [PMEDIA] ⭐️/084. Being Dead - Blanket of my Bone.mp3" May 18 10:16:13 volumio volumio[1020]: info: May 18 10:16:13 volumio volumio[1020]: ---------------------------- MPD announces system playlist update May 18 10:16:13 volumio volumio[1020]: info: Ignoring MPD Status Update May 18 10:16:13 volumio volumio[1020]: info: May 18 10:16:13 volumio volumio[1020]: ---------------------------- MPD announces system playlist update May 18 10:16:13 volumio volumio[1020]: info: Ignoring MPD Status Update May 18 10:16:13 volumio volumio[1020]: info: sendMpdCommand add "NAS/Yeoman/Best of 2024 Rock (Mp3 320kbps) [PMEDIA] ⭐️/084. Being Dead - Blanket of my Bone.mp3" took 8 milliseconds May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand play May 18 10:16:13 volumio volumio[1020]: info: May 18 10:16:13 volumio volumio[1020]: ---------------------------- MPD announces system playlist update May 18 10:16:13 volumio volumio[1020]: info: Ignoring MPD Status Update May 18 10:16:13 volumio volumio[1020]: info: May 18 10:16:13 volumio volumio[1020]: ---------------------------- MPD announces system playlist update May 18 10:16:13 volumio volumio[1020]: info: Ignoring MPD Status Update May 18 10:16:13 volumio volumio[1020]: info: May 18 10:16:13 volumio volumio[1020]: ---------------------------- MPD announces system playlist update May 18 10:16:13 volumio volumio[1020]: info: Ignoring MPD Status Update May 18 10:16:13 volumio volumio[1020]: info: ------------------------------ 9ms May 18 10:16:13 volumio volumio[1020]: info: ------------------------------ 8ms May 18 10:16:13 volumio volumio[1020]: info: sendMpdCommand play took 6 milliseconds May 18 10:16:13 volumio volumio[1020]: info: ------------------------------ 6ms May 18 10:16:13 volumio volumio[1020]: info: ------------------------------ 6ms May 18 10:16:13 volumio volumio[1020]: info: ------------------------------ 5ms May 18 10:16:13 volumio volumio[1020]: info: May 18 10:16:13 volumio volumio[1020]: ---------------------------- MPD announces system playlist update May 18 10:16:13 volumio volumio[1020]: info: Ignoring MPD Status Update May 18 10:16:13 volumio volumio[1020]: info: May 18 10:16:13 volumio volumio[1020]: ---------------------------- MPD announces system playlist update May 18 10:16:13 volumio volumio[1020]: info: Ignoring MPD Status Update May 18 10:16:13 volumio volumio[1020]: info: May 18 10:16:13 volumio volumio[1020]: ---------------------------- MPD announces system playlist update May 18 10:16:13 volumio volumio[1020]: info: Ignoring MPD Status Update May 18 10:16:13 volumio volumio[1020]: info: ------------------------------ 4ms May 18 10:16:13 volumio volumio[1020]: info: ------------------------------ 3ms May 18 10:16:13 volumio volumio[1020]: info: ------------------------------ 2ms May 18 10:16:13 volumio volumio[1020]: info: Successfully Added MyVolumio device May 18 10:16:13 volumio volumio[1020]: info: May 18 10:16:13 volumio volumio[1020]: ---------------------------- MPD announces state update: player May 18 10:16:13 volumio volumio[1020]: info: ControllerMpd::getState May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand status May 18 10:16:13 volumio volumio[1020]: info: May 18 10:16:13 volumio volumio[1020]: ---------------------------- MPD announces state update: player May 18 10:16:13 volumio volumio[1020]: info: ControllerMpd::getState May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand status May 18 10:16:13 volumio volumio[1020]: info: May 18 10:16:13 volumio volumio[1020]: ---------------------------- MPD announces state update: player May 18 10:16:13 volumio volumio[1020]: info: ControllerMpd::getState May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand status May 18 10:16:13 volumio volumio[1020]: info: May 18 10:16:13 volumio volumio[1020]: ---------------------------- MPD announces state update: player May 18 10:16:13 volumio volumio[1020]: info: ControllerMpd::getState May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand status May 18 10:16:13 volumio volumio[1020]: info: sendMpdCommand status took 50 milliseconds May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::parseState May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 10:16:13 volumio volumio[1020]: info: May 18 10:16:13 volumio volumio[1020]: ---------------------------- MPD announces state update: player May 18 10:16:13 volumio volumio[1020]: info: ControllerMpd::getState May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand status May 18 10:16:13 volumio volumio[1020]: info: sendMpdCommand status took 52 milliseconds May 18 10:16:13 volumio volumio[1020]: info: sendMpdCommand status took 51 milliseconds May 18 10:16:13 volumio volumio[1020]: info: sendMpdCommand status took 49 milliseconds May 18 10:16:13 volumio volumio[1020]: info: sendMpdCommand playlistinfo took 3 milliseconds May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::parseState May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::parseState May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::parseState May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::parseTrackInfo May 18 10:16:13 volumio volumio[1020]: info: ControllerMpd::pushState May 18 10:16:13 volumio volumio[1020]: info: CoreCommandRouter::servicePushState May 18 10:16:13 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:13 volumio volumio[1020]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Blanket of my Bone","artist":"Being Dead","album":"EELS","uri":"NAS/Yeoman/Best of 2024 Rock (Mp3 320kbps) [PMEDIA] ⭐️/084. Being Dead - Blanket of my Bone.mp3","trackType":"mp3"} May 18 10:16:13 volumio volumio[1020]: verbose: CURRENT POSITION 0 May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::syncState stateService play May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::syncState currentStatus stop May 18 10:16:13 volumio volumio[1020]: info: ------------------------------ 63ms May 18 10:16:13 volumio volumio[1020]: info: May 18 10:16:13 volumio volumio[1020]: ---------------------------- MPD announces state update: player May 18 10:16:13 volumio volumio[1020]: info: ControllerMpd::getState May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand status May 18 10:16:13 volumio volumio[1020]: info: May 18 10:16:13 volumio volumio[1020]: ---------------------------- MPD announces state update: player May 18 10:16:13 volumio volumio[1020]: info: ControllerMpd::getState May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand status May 18 10:16:13 volumio volumio[1020]: info: May 18 10:16:13 volumio volumio[1020]: ---------------------------- MPD announces state update: player May 18 10:16:13 volumio volumio[1020]: info: ControllerMpd::getState May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand status May 18 10:16:13 volumio volumio[1020]: info: sendMpdCommand status took 14 milliseconds May 18 10:16:13 volumio volumio[1020]: info: sendMpdCommand playlistinfo took 11 milliseconds May 18 10:16:13 volumio volumio[1020]: info: sendMpdCommand playlistinfo took 11 milliseconds May 18 10:16:13 volumio volumio[1020]: info: sendMpdCommand playlistinfo took 11 milliseconds May 18 10:16:13 volumio volumio[1020]: info: sendMpdCommand status took 5 milliseconds May 18 10:16:13 volumio volumio[1020]: info: sendMpdCommand status took 4 milliseconds May 18 10:16:13 volumio volumio[1020]: info: sendMpdCommand status took 3 milliseconds May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::parseState May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::parseTrackInfo May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::parseTrackInfo May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::parseTrackInfo May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::parseState May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::parseState May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::parseState May 18 10:16:13 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 10:16:13 volumio volumio[1020]: info: ControllerMpd::pushState May 18 10:16:13 volumio volumio[1020]: info: CoreCommandRouter::servicePushState May 18 10:16:13 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:13 volumio volumio[1020]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":194,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Blanket of my Bone","artist":"Being Dead","album":"EELS","uri":"NAS/Yeoman/Best of 2024 Rock (Mp3 320kbps) [PMEDIA] ⭐️/084. Being Dead - Blanket of my Bone.mp3","trackType":"mp3"} May 18 10:16:13 volumio volumio[1020]: verbose: CURRENT POSITION 0 May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::syncState stateService play May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::syncState currentStatus play May 18 10:16:13 volumio volumio[1020]: info: Received an update from plugin. extracting info from payload May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::pushState May 18 10:16:13 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:13 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 10:16:13 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::pushState May 18 10:16:13 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:13 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState May 18 10:16:13 volumio volumio[1020]: info: ControllerMpd::pushState May 18 10:16:13 volumio volumio[1020]: info: CoreCommandRouter::servicePushState May 18 10:16:13 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:13 volumio volumio[1020]: verbose: STATE SERVICE {"status":"play","position":0,"seek":685,"duration":194,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Blanket of my Bone","artist":"Being Dead","album":"EELS","uri":"NAS/Yeoman/Best of 2024 Rock (Mp3 320kbps) [PMEDIA] ⭐️/084. Being Dead - Blanket of my Bone.mp3","trackType":"mp3"} May 18 10:16:13 volumio volumio[1020]: verbose: CURRENT POSITION 0 May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::syncState stateService play May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::syncState currentStatus play May 18 10:16:13 volumio volumio[1020]: info: Received an update from plugin. extracting info from payload May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::pushState May 18 10:16:13 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:13 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 10:16:13 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::pushState May 18 10:16:13 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:13 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState May 18 10:16:13 volumio volumio[1020]: info: ControllerMpd::pushState May 18 10:16:13 volumio volumio[1020]: info: CoreCommandRouter::servicePushState May 18 10:16:13 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:13 volumio volumio[1020]: verbose: STATE SERVICE {"status":"play","position":0,"seek":685,"duration":194,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Blanket of my Bone","artist":"Being Dead","album":"EELS","uri":"NAS/Yeoman/Best of 2024 Rock (Mp3 320kbps) [PMEDIA] ⭐️/084. Being Dead - Blanket of my Bone.mp3","trackType":"mp3"} May 18 10:16:13 volumio volumio[1020]: verbose: CURRENT POSITION 0 May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::syncState stateService play May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::syncState currentStatus play May 18 10:16:13 volumio volumio[1020]: info: Received an update from plugin. extracting info from payload May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::pushState May 18 10:16:13 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:13 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState May 18 10:16:13 volumio volumio[1020]: info: CoreStateMachine::pushState May 18 10:16:13 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:13 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState May 18 10:16:13 volumio volumio[1020]: info: ------------------------------ 104ms May 18 10:16:13 volumio volumio[1020]: info: ------------------------------ 103ms May 18 10:16:13 volumio volumio[1020]: info: ------------------------------ 103ms May 18 10:16:14 volumio volumio[1020]: info: sendMpdCommand playlistinfo took 43 milliseconds May 18 10:16:14 volumio volumio[1020]: info: sendMpdCommand playlistinfo took 43 milliseconds May 18 10:16:14 volumio volumio[1020]: info: sendMpdCommand playlistinfo took 42 milliseconds May 18 10:16:14 volumio volumio[1020]: info: sendMpdCommand playlistinfo took 42 milliseconds May 18 10:16:14 volumio volumio[1020]: verbose: ControllerMpd::parseTrackInfo May 18 10:16:14 volumio volumio[1020]: verbose: ControllerMpd::parseTrackInfo May 18 10:16:14 volumio volumio[1020]: verbose: ControllerMpd::parseTrackInfo May 18 10:16:14 volumio volumio[1020]: verbose: ControllerMpd::parseTrackInfo May 18 10:16:14 volumio volumio[1020]: info: ControllerMpd::pushState May 18 10:16:14 volumio volumio[1020]: info: CoreCommandRouter::servicePushState May 18 10:16:14 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:14 volumio volumio[1020]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":194,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Blanket of my Bone","artist":"Being Dead","album":"EELS","uri":"NAS/Yeoman/Best of 2024 Rock (Mp3 320kbps) [PMEDIA] ⭐️/084. Being Dead - Blanket of my Bone.mp3","trackType":"mp3"} May 18 10:16:14 volumio volumio[1020]: verbose: CURRENT POSITION 0 May 18 10:16:14 volumio volumio[1020]: info: CoreStateMachine::syncState stateService play May 18 10:16:14 volumio volumio[1020]: info: CoreStateMachine::syncState currentStatus play May 18 10:16:14 volumio volumio[1020]: info: Received an update from plugin. extracting info from payload May 18 10:16:14 volumio volumio[1020]: info: CoreStateMachine::pushState May 18 10:16:14 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:14 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 10:16:14 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState May 18 10:16:14 volumio volumio[1020]: info: CoreStateMachine::pushState May 18 10:16:14 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:14 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState May 18 10:16:14 volumio volumio[1020]: info: ControllerMpd::pushState May 18 10:16:14 volumio volumio[1020]: info: CoreCommandRouter::servicePushState May 18 10:16:14 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:14 volumio volumio[1020]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":194,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Blanket of my Bone","artist":"Being Dead","album":"EELS","uri":"NAS/Yeoman/Best of 2024 Rock (Mp3 320kbps) [PMEDIA] ⭐️/084. Being Dead - Blanket of my Bone.mp3","trackType":"mp3"} May 18 10:16:14 volumio volumio[1020]: verbose: CURRENT POSITION 0 May 18 10:16:14 volumio volumio[1020]: info: CoreStateMachine::syncState stateService play May 18 10:16:14 volumio volumio[1020]: info: CoreStateMachine::syncState currentStatus play May 18 10:16:14 volumio volumio[1020]: info: Received an update from plugin. extracting info from payload May 18 10:16:14 volumio volumio[1020]: info: CoreStateMachine::pushState May 18 10:16:14 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:14 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState May 18 10:16:14 volumio volumio[1020]: info: CoreStateMachine::pushState May 18 10:16:14 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:14 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState May 18 10:16:14 volumio volumio[1020]: info: ControllerMpd::pushState May 18 10:16:14 volumio volumio[1020]: info: CoreCommandRouter::servicePushState May 18 10:16:14 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:14 volumio volumio[1020]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":194,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Blanket of my Bone","artist":"Being Dead","album":"EELS","uri":"NAS/Yeoman/Best of 2024 Rock (Mp3 320kbps) [PMEDIA] ⭐️/084. Being Dead - Blanket of my Bone.mp3","trackType":"mp3"} May 18 10:16:14 volumio volumio[1020]: verbose: CURRENT POSITION 0 May 18 10:16:14 volumio volumio[1020]: info: CoreStateMachine::syncState stateService play May 18 10:16:14 volumio volumio[1020]: info: CoreStateMachine::syncState currentStatus play May 18 10:16:14 volumio volumio[1020]: info: Received an update from plugin. extracting info from payload May 18 10:16:14 volumio volumio[1020]: info: CoreStateMachine::pushState May 18 10:16:14 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:14 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState May 18 10:16:14 volumio volumio[1020]: info: CoreStateMachine::pushState May 18 10:16:14 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:14 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState May 18 10:16:14 volumio volumio[1020]: info: ControllerMpd::pushState May 18 10:16:14 volumio volumio[1020]: info: CoreCommandRouter::servicePushState May 18 10:16:14 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:14 volumio volumio[1020]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":194,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Blanket of my Bone","artist":"Being Dead","album":"EELS","uri":"NAS/Yeoman/Best of 2024 Rock (Mp3 320kbps) [PMEDIA] ⭐️/084. Being Dead - Blanket of my Bone.mp3","trackType":"mp3"} May 18 10:16:14 volumio volumio[1020]: verbose: CURRENT POSITION 0 May 18 10:16:14 volumio volumio[1020]: info: CoreStateMachine::syncState stateService play May 18 10:16:14 volumio volumio[1020]: info: CoreStateMachine::syncState currentStatus play May 18 10:16:14 volumio volumio[1020]: info: Received an update from plugin. extracting info from payload May 18 10:16:14 volumio volumio[1020]: info: CoreStateMachine::pushState May 18 10:16:14 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:14 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState May 18 10:16:14 volumio volumio[1020]: info: CoreStateMachine::pushState May 18 10:16:14 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:14 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState May 18 10:16:14 volumio systemd[1]: systemd-timedated.service: Succeeded. May 18 10:16:14 volumio volumio[1020]: info: ------------------------------ 98ms May 18 10:16:14 volumio volumio[1020]: info: ------------------------------ 89ms May 18 10:16:14 volumio volumio[1020]: info: ------------------------------ 89ms May 18 10:16:14 volumio volumio[1020]: info: ------------------------------ 87ms May 18 10:16:14 volumio volumio[1020]: info: Updating MyVolumio device info May 18 10:16:14 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 10:16:14 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 10:16:14 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 10:16:14 volumio volumio[1020]: info: Successfully Updated MyVolumio device May 18 10:16:16 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 18 10:16:16 volumio volumio[1020]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 18 10:16:16 volumio volumio[1020]: info: CoreCommandRouter::volumioGetState May 18 10:16:16 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:18 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 18 10:16:18 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 10:16:18 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 18 10:16:18 volumio volumio-remote-updater[638]: No test mode May 18 10:16:18 volumio volumio-remote-updater[638]: No alpha test mode May 18 10:16:18 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 18 10:16:19 volumio volumio[1020]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 18 10:16:19 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 18 10:16:21 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:16:21 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 18 10:16:21 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 18 10:16:21 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 18 10:16:21 volumio volumio[1020]: info: CoreCommandRouter::volumioGetBrowseSources May 18 10:16:21 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 18 10:16:22 volumio volumio[1020]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 18 10:16:22 volumio volumio[1020]: info: Completed starting MyVolumio Plugin May 18 10:16:22 volumio volumio[1020]: [Metrics] CommandRouter: 38s 640.81ms May 18 10:16:22 volumio volumio[1020]: info: CoreCommandRouter::volumiosetStartupVolume May 18 10:16:22 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:16:22 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 10:16:22 volumio volumio[1020]: info: CoreCommandRouter::Close All Modals sent May 18 10:16:22 volumio volumio[1020]: info: CoreCommandRouter::Close All Modals sent May 18 10:16:22 volumio volumio[1020]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav May 18 10:16:22 volumio volumio[1020]: aplay: main:828: audio open error: Device or resource busy May 18 10:16:23 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 18 10:16:23 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 18 10:16:23 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 18 10:16:23 volumio volumio[1020]: info: CoreCommandRouter::volumioPause May 18 10:16:23 volumio volumio[1020]: info: CoreStateMachine::pause May 18 10:16:23 volumio volumio[1020]: info: CoreStateMachine::stPlaybackTimer May 18 10:16:23 volumio volumio[1020]: info: CoreStateMachine::servicePause May 18 10:16:23 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:23 volumio volumio[1020]: info: CoreCommandRouter::servicePause May 18 10:16:23 volumio volumio[1020]: info: ControllerMpd::pause May 18 10:16:23 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand pause May 18 10:16:23 volumio volumio[1020]: info: May 18 10:16:23 volumio volumio[1020]: ---------------------------- MPD announces state update: player May 18 10:16:23 volumio volumio[1020]: info: sendMpdCommand pause took 7 milliseconds May 18 10:16:23 volumio volumio[1020]: info: ControllerMpd::getState May 18 10:16:23 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand status May 18 10:16:23 volumio volumio[1020]: info: May 18 10:16:23 volumio volumio[1020]: ---------------------------- MPD announces state update: player May 18 10:16:23 volumio volumio[1020]: info: ControllerMpd::getState May 18 10:16:23 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand status May 18 10:16:23 volumio volumio[1020]: info: May 18 10:16:23 volumio volumio[1020]: ---------------------------- MPD announces state update: player May 18 10:16:23 volumio volumio[1020]: info: ControllerMpd::getState May 18 10:16:23 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand status May 18 10:16:23 volumio volumio[1020]: info: May 18 10:16:23 volumio volumio[1020]: ---------------------------- MPD announces state update: player May 18 10:16:23 volumio volumio[1020]: info: ControllerMpd::getState May 18 10:16:23 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand status May 18 10:16:23 volumio volumio[1020]: info: sendMpdCommand status took 7 milliseconds May 18 10:16:23 volumio volumio[1020]: info: sendMpdCommand status took 3 milliseconds May 18 10:16:23 volumio volumio[1020]: info: sendMpdCommand status took 3 milliseconds May 18 10:16:23 volumio volumio[1020]: info: sendMpdCommand status took 2 milliseconds May 18 10:16:23 volumio volumio[1020]: verbose: ControllerMpd::parseState May 18 10:16:23 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 10:16:23 volumio volumio[1020]: verbose: ControllerMpd::parseState May 18 10:16:23 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 10:16:23 volumio volumio[1020]: verbose: ControllerMpd::parseState May 18 10:16:23 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 10:16:23 volumio volumio[1020]: verbose: ControllerMpd::parseState May 18 10:16:23 volumio volumio[1020]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 10:16:23 volumio volumio[1020]: info: sendMpdCommand playlistinfo took 4 milliseconds May 18 10:16:23 volumio volumio[1020]: info: sendMpdCommand playlistinfo took 3 milliseconds May 18 10:16:23 volumio volumio[1020]: info: sendMpdCommand playlistinfo took 4 milliseconds May 18 10:16:23 volumio volumio[1020]: verbose: ControllerMpd::parseTrackInfo May 18 10:16:23 volumio volumio[1020]: info: ControllerMpd::pushState May 18 10:16:23 volumio volumio[1020]: info: CoreCommandRouter::servicePushState May 18 10:16:23 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:23 volumio volumio[1020]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":10079,"duration":194,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Blanket of my Bone","artist":"Being Dead","album":"EELS","uri":"NAS/Yeoman/Best of 2024 Rock (Mp3 320kbps) [PMEDIA] ⭐️/084. Being Dead - Blanket of my Bone.mp3","trackType":"mp3"} May 18 10:16:23 volumio volumio[1020]: verbose: CURRENT POSITION 0 May 18 10:16:23 volumio volumio[1020]: info: CoreStateMachine::syncState stateService pause May 18 10:16:23 volumio volumio[1020]: info: CoreStateMachine::syncState currentStatus pause May 18 10:16:23 volumio volumio[1020]: info: CoreStateMachine::pushState May 18 10:16:23 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:23 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 10:16:23 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState May 18 10:16:23 volumio volumio[1020]: info: CoreStateMachine::stPlaybackTimer May 18 10:16:23 volumio volumio[1020]: info: ControllerMpd::pushState May 18 10:16:23 volumio volumio[1020]: info: CoreCommandRouter::servicePushState May 18 10:16:23 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:23 volumio volumio[1020]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":10079,"duration":194,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Blanket of my Bone","artist":"Being Dead","album":"EELS","uri":"NAS/Yeoman/Best of 2024 Rock (Mp3 320kbps) [PMEDIA] ⭐️/084. Being Dead - Blanket of my Bone.mp3","trackType":"mp3"} May 18 10:16:23 volumio volumio[1020]: verbose: CURRENT POSITION 0 May 18 10:16:23 volumio volumio[1020]: info: CoreStateMachine::syncState stateService pause May 18 10:16:23 volumio volumio[1020]: info: CoreStateMachine::syncState currentStatus pause May 18 10:16:23 volumio volumio[1020]: info: CoreStateMachine::pushState May 18 10:16:23 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:23 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState May 18 10:16:23 volumio volumio[1020]: info: CoreStateMachine::stPlaybackTimer May 18 10:16:23 volumio volumio[1020]: info: ControllerMpd::pushState May 18 10:16:23 volumio volumio[1020]: info: CoreCommandRouter::servicePushState May 18 10:16:23 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:23 volumio volumio[1020]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":10079,"duration":194,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Blanket of my Bone","artist":"Being Dead","album":"EELS","uri":"NAS/Yeoman/Best of 2024 Rock (Mp3 320kbps) [PMEDIA] ⭐️/084. Being Dead - Blanket of my Bone.mp3","trackType":"mp3"} May 18 10:16:23 volumio volumio[1020]: verbose: CURRENT POSITION 0 May 18 10:16:23 volumio volumio[1020]: info: CoreStateMachine::syncState stateService pause May 18 10:16:23 volumio volumio[1020]: info: CoreStateMachine::syncState currentStatus pause May 18 10:16:23 volumio volumio[1020]: info: CoreStateMachine::pushState May 18 10:16:23 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:23 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState May 18 10:16:23 volumio volumio[1020]: info: CoreStateMachine::stPlaybackTimer May 18 10:16:23 volumio volumio[1020]: info: ControllerMpd::pushState May 18 10:16:23 volumio volumio[1020]: info: CoreCommandRouter::servicePushState May 18 10:16:23 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:23 volumio volumio[1020]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":10079,"duration":194,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Blanket of my Bone","artist":"Being Dead","album":"EELS","uri":"NAS/Yeoman/Best of 2024 Rock (Mp3 320kbps) [PMEDIA] ⭐️/084. Being Dead - Blanket of my Bone.mp3","trackType":"mp3"} May 18 10:16:23 volumio volumio[1020]: verbose: CURRENT POSITION 0 May 18 10:16:23 volumio volumio[1020]: info: CoreStateMachine::syncState stateService pause May 18 10:16:23 volumio volumio[1020]: info: CoreStateMachine::syncState currentStatus pause May 18 10:16:23 volumio volumio[1020]: info: CoreStateMachine::pushState May 18 10:16:23 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 May 18 10:16:23 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState May 18 10:16:23 volumio volumio[1020]: info: CoreStateMachine::stPlaybackTimer May 18 10:16:23 volumio volumio[1020]: info: ------------------------------ 36ms May 18 10:16:23 volumio volumio[1020]: info: ------------------------------ 34ms May 18 10:16:23 volumio volumio[1020]: info: ------------------------------ 32ms May 18 10:16:23 volumio volumio[1020]: info: ------------------------------ 31ms May 18 10:16:28 volumio volumio[1020]: info: CoreCommandRouter::getUIConfigOnPlugin May 18 10:16:28 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 18 10:16:29 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 18 10:16:29 volumio sudo[1516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 18 10:16:29 volumio sudo[1516]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:16:29 volumio sudo[1522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 18 10:16:29 volumio sudo[1522]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:16:29 volumio sudo[1522]: pam_unix(sudo:session): session closed for user root May 18 10:16:29 volumio sudo[1516]: pam_unix(sudo:session): session closed for user root May 18 10:16:29 volumio sudo[1529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 18 10:16:29 volumio sudo[1529]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:16:29 volumio sudo[1533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 18 10:16:29 volumio sudo[1533]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:16:29 volumio sudo[1529]: pam_unix(sudo:session): session closed for user root May 18 10:16:29 volumio sudo[1533]: pam_unix(sudo:session): session closed for user root May 18 10:16:29 volumio sudo[1542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 18 10:16:29 volumio sudo[1542]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:16:29 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 18 10:16:29 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 18 10:16:29 volumio sudo[1542]: pam_unix(sudo:session): session closed for user root May 18 10:16:29 volumio sudo[1544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 10:16:29 volumio sudo[1544]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:16:29 volumio sudo[1544]: pam_unix(sudo:session): session closed for user root May 18 10:16:29 volumio sudo[1548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 18 10:16:29 volumio sudo[1548]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:16:29 volumio volumio[1020]: info: BOOT COMPLETED May 18 10:16:32 volumio sudo[1548]: pam_unix(sudo:session): session closed for user root May 18 10:16:35 volumio ntpd[812]: Soliciting pool server 202.124.96.215 May 18 10:16:35 volumio ntpd[812]: Soliciting pool server 203.190.214.199 May 18 10:16:36 volumio ntpd[812]: Soliciting pool server 192.107.172.19 May 18 10:16:36 volumio ntpd[812]: Soliciting pool server 202.21.136.135 May 18 10:16:36 volumio ntpd[812]: Soliciting pool server 162.159.200.1 May 18 10:16:36 volumio ntpd[812]: Soliciting pool server 103.242.70.5 May 18 10:16:36 volumio ntpd[812]: Soliciting pool server 202.68.92.244 May 18 10:16:36 volumio ntpd[812]: Soliciting pool server 162.159.200.1 May 18 10:16:37 volumio ntpd[812]: Soliciting pool server 43.252.70.34 May 18 10:16:37 volumio ntpd[812]: Soliciting pool server 114.110.37.123 May 18 10:16:37 volumio ntpd[812]: Soliciting pool server 103.242.68.68 May 18 10:16:38 volumio ntpd[812]: Soliciting pool server 103.242.68.69 May 18 10:16:38 volumio ntpd[812]: Soliciting pool server 2606:4700:f1::1 May 18 10:16:43 volumio ntpd[812]: receive: Unexpected origin timestamp 0xebd3894a.4a67451a does not match aorg 0000000000.00000000 from server@103.242.68.68 xmt 0xebd3894b.8076929b May 18 10:16:43 volumio ntpd[812]: receive: Unexpected origin timestamp 0xebd3894a.4a690076 does not match aorg 0000000000.00000000 from server@192.107.172.19 xmt 0xebd3894b.80139f35 May 18 10:16:43 volumio ntpd[812]: receive: Unexpected origin timestamp 0xebd3894a.4a64fd24 does not match aorg 0000000000.00000000 from server@43.252.70.34 xmt 0xebd3894b.804e831a May 18 10:16:43 volumio ntpd[812]: receive: Unexpected origin timestamp 0xebd3894a.4a69f0fa does not match aorg 0000000000.00000000 from server@202.124.96.215 xmt 0xebd3894b.81893fb2 May 18 10:17:13 volumio volumio[1020]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] May 18 10:17:13 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet May 18 10:17:17 volumio volumio[1020]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] May 18 10:17:17 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet May 18 10:17:17 volumio sudo[1627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces May 18 10:17:17 volumio sudo[1627]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:17 volumio sudo[1627]: pam_unix(sudo:session): session closed for user root May 18 10:17:17 volumio sudo[1629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf May 18 10:17:17 volumio sudo[1629]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:17 volumio sudo[1629]: pam_unix(sudo:session): session closed for user root May 18 10:17:17 volumio volumio[1020]: info: Permissions for /etc/network/interfaces set May 18 10:17:17 volumio volumio[1020]: info: Restarting Wireless Network May 18 10:17:17 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 18 10:17:17 volumio volumio[1020]: info: Discovery: Restarting Advertising due to device name change May 18 10:17:17 volumio sudo[1632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service May 18 10:17:17 volumio sudo[1632]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:17 volumio systemd[1]: wireless.service: Killing process 919 (wpa_supplicant) with signal SIGKILL. May 18 10:17:17 volumio systemd[1]: wireless.service: Killing process 931 (dhcpcd) with signal SIGKILL. May 18 10:17:17 volumio systemd[1]: Stopping Wireless Services... May 18 10:17:18 volumio systemd[1]: wireless.service: Succeeded. May 18 10:17:18 volumio systemd[1]: Stopped Wireless Services. May 18 10:17:18 volumio systemd[1]: Starting Wireless Services... May 18 10:17:18 volumio wireless.js[1634]: Cleaning previous... May 18 10:17:18 volumio sudo[1648]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 18 10:17:18 volumio sudo[1648]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:18 volumio avahi-daemon[652]: Withdrawing address record for 192.168.1.14 on wlan0. May 18 10:17:18 volumio avahi-daemon[652]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.14. May 18 10:17:18 volumio avahi-daemon[652]: Interface wlan0.IPv4 no longer relevant for mDNS. May 18 10:17:18 volumio sudo[1648]: pam_unix(sudo:session): session closed for user root May 18 10:17:18 volumio volumio[1020]: info: Discovery: A device disappeared from network May 18 10:17:18 volumio volumio[1020]: info: Discovery: Device volumio disappeared from network May 18 10:17:18 volumio sudo[1650]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 18 10:17:18 volumio sudo[1650]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:19 volumio sudo[1650]: pam_unix(sudo:session): session closed for user root May 18 10:17:19 volumio wireless.js[1634]: Stopped aP May 18 10:17:19 volumio sudo[1657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 18 10:17:19 volumio sudo[1657]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:19 volumio sudo[1657]: pam_unix(sudo:session): session closed for user root May 18 10:17:19 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 18 10:17:19 volumio sudo[1659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 18 10:17:19 volumio sudo[1659]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:19 volumio sudo[1659]: pam_unix(sudo:session): session closed for user root May 18 10:17:19 volumio sudo[1666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 18 10:17:19 volumio sudo[1666]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:19 volumio sudo[1666]: pam_unix(sudo:session): session closed for user root May 18 10:17:19 volumio sudo[1668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 18 10:17:19 volumio sudo[1668]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:20 volumio ntpd[812]: Deleting interface #3 wlan0, 192.168.1.14#123, interface stats: received=78, sent=78, dropped=0, active_time=96 secs May 18 10:17:20 volumio ntpd[812]: 103.242.68.69 local addr 192.168.1.14 -> May 18 10:17:20 volumio ntpd[812]: 43.252.70.34 local addr 192.168.1.14 -> May 18 10:17:20 volumio ntpd[812]: 103.242.68.68 local addr 192.168.1.14 -> May 18 10:17:20 volumio ntpd[812]: 114.110.37.123 local addr 192.168.1.14 -> May 18 10:17:20 volumio ntpd[812]: 202.68.92.244 local addr 192.168.1.14 -> May 18 10:17:20 volumio ntpd[812]: 162.159.200.1 local addr 192.168.1.14 -> May 18 10:17:20 volumio ntpd[812]: 103.242.70.5 local addr 192.168.1.14 -> May 18 10:17:20 volumio ntpd[812]: 202.21.136.135 local addr 192.168.1.14 -> May 18 10:17:20 volumio ntpd[812]: 192.107.172.19 local addr 192.168.1.14 -> May 18 10:17:20 volumio ntpd[812]: 203.190.214.199 local addr 192.168.1.14 -> May 18 10:17:20 volumio ntpd[812]: 202.124.96.215 local addr 192.168.1.14 -> May 18 10:17:21 volumio sudo[1668]: pam_unix(sudo:session): session closed for user root May 18 10:17:21 volumio wireless.js[1634]: SETTING APPROPRIATE REG DOMAIN: NZ May 18 10:17:21 volumio sudo[1673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 18 10:17:21 volumio sudo[1673]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:21 volumio sudo[1673]: pam_unix(sudo:session): session closed for user root May 18 10:17:21 volumio sudo[1675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set NZ May 18 10:17:21 volumio sudo[1675]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:21 volumio sudo[1675]: pam_unix(sudo:session): session closed for user root May 18 10:17:21 volumio wireless.js[1634]: SUCCESSFULLY SET NEW REGDOMAIN: NZ May 18 10:17:21 volumio wireless.js[1634]: Start wireless flow May 18 10:17:21 volumio wireless.js[1634]: Stopped hotspot (if there).. May 18 10:17:21 volumio sudo[1680]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 18 10:17:21 volumio sudo[1680]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:21 volumio sudo[1680]: pam_unix(sudo:session): session closed for user root May 18 10:17:21 volumio sudo[1682]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 18 10:17:21 volumio sudo[1682]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:22 volumio sudo[1682]: pam_unix(sudo:session): session closed for user root May 18 10:17:22 volumio wireless.js[1634]: DHCP IP May 18 10:17:22 volumio wireless.js[1634]: Start ap May 18 10:17:22 volumio wpa_supplicant[1684]: Successfully initialized wpa_supplicant May 18 10:17:22 volumio sudo[1685]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd May 18 10:17:22 volumio sudo[1685]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:22 volumio dhcpcd[1686]: main: control_open: Connection refused May 18 10:17:22 volumio dhcpcd[1686]: dev: loaded udev May 18 10:17:22 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 18 10:17:22 volumio dhcpcd-run-hooks[1712]: wlan0: starting wpa_supplicant May 18 10:17:22 volumio dhcpcd-run-hooks[1717]: wlan0: failed to start wpa_supplicant May 18 10:17:22 volumio dhcpcd-run-hooks[1718]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma May 18 10:17:22 volumio dhcpcd[1686]: wlan0: connected to Access Point `' May 18 10:17:22 volumio dhcpcd[1686]: no interfaces have a carrier May 18 10:17:22 volumio dhcpcd[1686]: forked to background, child pid 1737 May 18 10:17:22 volumio dhcpcd[1737]: eth0: waiting for carrier May 18 10:17:22 volumio dhcpcd[1737]: wlan0: waiting for carrier May 18 10:17:22 volumio sudo[1685]: pam_unix(sudo:session): session closed for user root May 18 10:17:22 volumio volumio[1020]: info: Discovery: Started advertising with name: Volumio May 18 10:17:23 volumio wireless.js[1634]: trying... May 18 10:17:23 volumio sudo[1740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 10:17:23 volumio sudo[1740]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:23 volumio sudo[1740]: pam_unix(sudo:session): session closed for user root May 18 10:17:24 volumio wireless.js[1634]: trying... May 18 10:17:24 volumio sudo[1781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 10:17:24 volumio sudo[1781]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:24 volumio sudo[1781]: pam_unix(sudo:session): session closed for user root May 18 10:17:25 volumio wireless.js[1634]: trying... May 18 10:17:25 volumio sudo[1784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 10:17:25 volumio sudo[1784]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:25 volumio sudo[1784]: pam_unix(sudo:session): session closed for user root May 18 10:17:26 volumio wireless.js[1634]: trying... May 18 10:17:26 volumio sudo[1787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 10:17:26 volumio sudo[1787]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:26 volumio sudo[1787]: pam_unix(sudo:session): session closed for user root May 18 10:17:26 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 18 10:17:26 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 18 10:17:26 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 18 10:17:27 volumio volumio[1020]: info: Volumio Network Manager: Network status updated: 0 May 18 10:17:27 volumio wireless.js[1634]: trying... May 18 10:17:27 volumio sudo[1804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 10:17:27 volumio sudo[1804]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:27 volumio sudo[1804]: pam_unix(sudo:session): session closed for user root May 18 10:17:28 volumio wireless.js[1634]: trying... May 18 10:17:28 volumio sudo[1810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 10:17:28 volumio sudo[1810]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:28 volumio sudo[1810]: pam_unix(sudo:session): session closed for user root May 18 10:17:28 volumio wpa_supplicant[1716]: wlan0: Trying to associate with SSID 'StandenTowers5G' May 18 10:17:28 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 18 10:17:28 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 May 18 10:17:28 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 May 18 10:17:28 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 May 18 10:17:28 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 May 18 10:17:28 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 May 18 10:17:28 volumio dhcpcd[1737]: wlan0: carrier acquired May 18 10:17:28 volumio dhcpcd[1737]: wlan0: connected to Access Point `StandenTowers5G' May 18 10:17:28 volumio wpa_supplicant[1716]: wlan0: Associated with b4:fb:e4:db:77:6e May 18 10:17:28 volumio wpa_supplicant[1716]: wlan0: CTRL-EVENT-CONNECTED - Connection to b4:fb:e4:db:77:6e completed [id=0 id_str=] May 18 10:17:28 volumio wpa_supplicant[1716]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 18 10:17:28 volumio wpa_supplicant[1716]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=NZ May 18 10:17:28 volumio dhcpcd[1737]: DUID 00:01:00:01:2f:b6:48:f8:dc:a6:32:49:c9:6b May 18 10:17:28 volumio dhcpcd[1737]: wlan0: IAID 32:49:c9:6b May 18 10:17:28 volumio dhcpcd[1737]: wlan0: adding address fe80::dea6:32ff:fe49:c96b May 18 10:17:28 volumio dhcpcd[1737]: ipv6_addaddr1: Permission denied May 18 10:17:28 volumio dhcpcd[1737]: wlan0: probing address 192.168.1.14/24 May 18 10:17:28 volumio dhcpcd[1737]: wlan0: carrier lost May 18 10:17:28 volumio dhcpcd[1737]: wlan0: carrier acquired May 18 10:17:29 volumio dhcpcd[1737]: wlan0: IAID 32:49:c9:6b May 18 10:17:29 volumio dhcpcd[1737]: wlan0: probing address 192.168.1.14/24 May 18 10:17:29 volumio wireless.js[1634]: trying... May 18 10:17:29 volumio sudo[1846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 10:17:29 volumio sudo[1846]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:29 volumio sudo[1846]: pam_unix(sudo:session): session closed for user root May 18 10:17:29 volumio dhcpcd[1737]: wlan0: soliciting an IPv6 router May 18 10:17:30 volumio wireless.js[1634]: trying... May 18 10:17:30 volumio sudo[1849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 10:17:30 volumio sudo[1849]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:30 volumio sudo[1849]: pam_unix(sudo:session): session closed for user root May 18 10:17:31 volumio wireless.js[1634]: trying... May 18 10:17:31 volumio sudo[1852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 10:17:31 volumio sudo[1852]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:31 volumio sudo[1852]: pam_unix(sudo:session): session closed for user root May 18 10:17:32 volumio wireless.js[1634]: trying... May 18 10:17:32 volumio sudo[1855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 10:17:32 volumio sudo[1855]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:32 volumio sudo[1855]: pam_unix(sudo:session): session closed for user root May 18 10:17:33 volumio wireless.js[1634]: trying... May 18 10:17:33 volumio sudo[1858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 10:17:33 volumio sudo[1858]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:33 volumio sudo[1858]: pam_unix(sudo:session): session closed for user root May 18 10:17:34 volumio dhcpcd[1737]: wlan0: using static address 192.168.1.14/24 May 18 10:17:34 volumio avahi-daemon[652]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.14. May 18 10:17:34 volumio avahi-daemon[652]: New relevant interface wlan0.IPv4 for mDNS. May 18 10:17:34 volumio dhcpcd[1737]: wlan0: adding route to 192.168.1.0/24 May 18 10:17:34 volumio dhcpcd[1737]: wlan0: adding default route via 192.168.1.1 May 18 10:17:34 volumio avahi-daemon[652]: Registering new address record for 192.168.1.14 on wlan0.IPv4. May 18 10:17:34 volumio volumio[1020]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 10:17:34 volumio volumio[1020]: Error: getaddrinfo ENOTFOUND lastfm.freetls.fastly.net May 18 10:17:34 volumio volumio[1020]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { May 18 10:17:34 volumio volumio[1020]: errno: -3007, May 18 10:17:34 volumio volumio[1020]: code: 'ENOTFOUND', May 18 10:17:34 volumio volumio[1020]: syscall: 'getaddrinfo', May 18 10:17:34 volumio volumio[1020]: hostname: 'lastfm.freetls.fastly.net' May 18 10:17:34 volumio volumio[1020]: } May 18 10:17:34 volumio volumio[1020]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 10:17:34 volumio wireless.js[1634]: trying... May 18 10:17:34 volumio sudo[1889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 10:17:34 volumio sudo[1889]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:34 volumio sudo[1889]: pam_unix(sudo:session): session closed for user root May 18 10:17:34 volumio wireless.js[1634]: Connected to: ----StandenTowers5G May 18 10:17:34 volumio wireless.js[1634]: ---- May 18 10:17:34 volumio sudo[1892]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 10:17:34 volumio sudo[1892]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:17:34 volumio sudo[1892]: pam_unix(sudo:session): session closed for user root May 18 10:17:34 volumio wireless.js[1634]: ... joined AP, wlan0 IPv4 is 192.168.1.14, ipV6 is undefined May 18 10:17:34 volumio wireless.js[1634]: It's done! AP May 18 10:17:34 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 18 10:17:34 volumio systemd[1]: Started Wireless Services. May 18 10:17:34 volumio sudo[1632]: pam_unix(sudo:session): session closed for user root May 18 10:17:34 volumio sudo[1899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-18 10:16 May 18 10:17:34 volumio sudo[1899]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"