May 26 15:08:03 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 15:08:03 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:08:03 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 26 15:08:04 j1800-volumio volumio-remote-updater[758]: Test mode disabled May 26 15:08:04 j1800-volumio volumio-remote-updater[758]: Alpha mode disabled May 26 15:08:04 j1800-volumio volumio-remote-updater[758]: Alpha legacy test mode disabled May 26 15:08:04 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 26 15:08:04 j1800-volumio dhcpcd[813]: eth0: failed to renew DHCP, rebinding May 26 15:08:04 j1800-volumio dhcpcd[813]: eth0: leased 192.168.2.253 for 180 seconds May 26 15:08:04 j1800-volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. May 26 15:08:04 j1800-volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... May 26 15:08:04 j1800-volumio systemd[1]: welcome.service: Deactivated successfully. May 26 15:08:04 j1800-volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 26 15:08:04 j1800-volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 26 15:08:04 j1800-volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 26 15:08:04 j1800-volumio welcome[3594]: Resolved ip:[1] 192.168.2.253 May 26 15:08:04 j1800-volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 26 15:08:04 j1800-volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. May 26 15:08:04 j1800-volumio volumio[3115]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 26 15:08:04 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 26 15:08:04 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:08:04 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:08:04 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 26 15:08:04 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 26 15:08:04 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 26 15:08:04 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetBrowseSources May 26 15:08:04 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 15:08:05 j1800-volumio volumio[3115]: info: Received Get System Info May 26 15:08:05 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 15:08:05 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 15:08:05 j1800-volumio volumio[3115]: info: Discovery: Getting this device information May 26 15:08:05 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetState May 26 15:08:05 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:08:05 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 26 15:08:05 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 26 15:08:05 j1800-volumio volumio[3115]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 26 15:08:05 j1800-volumio volumio[3115]: info: Completed starting MyVolumio Plugin May 26 15:08:05 j1800-volumio volumio[3115]: [Metrics] CommandRouter: 35s 562.16ms May 26 15:08:05 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumiosetStartupVolume May 26 15:08:05 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:08:05 j1800-volumio volumio[3115]: info: VolumeController:: Setting startup Volume 40 May 26 15:08:05 j1800-volumio volumio[3115]: info: VolumeController::SetAlsaVolume40 May 26 15:08:05 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:08:05 j1800-volumio volumio[3115]: info: CoreCommandRouter::Close All Modals sent May 26 15:08:05 j1800-volumio volumio[3115]: info: CoreCommandRouter::Close All Modals sent May 26 15:08:05 j1800-volumio volumio[3115]: info: CoreStateMachine::pushState May 26 15:08:05 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 15:08:05 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioPushState May 26 15:08:05 j1800-volumio volumio[3115]: info: PeppyMeterBasic ---peppymeterbasic status play May 26 15:08:05 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetQueue May 26 15:08:05 j1800-volumio volumio[3115]: info: CoreStateMachine::getQueue May 26 15:08:05 j1800-volumio volumio[3115]: info: CorePlayQueue::getQueue May 26 15:08:05 j1800-volumio sudo[3610]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 26 15:08:05 j1800-volumio sudo[3610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:05 j1800-volumio volumio[3115]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav May 26 15:08:05 j1800-volumio volumio[3115]: aplay: main:831: audio open error: Device or resource busy May 26 15:08:05 j1800-volumio sudo[3610]: pam_unix(sudo:session): session closed for user root May 26 15:08:05 j1800-volumio volumio[3115]: info: peppymeterbasic Daemon Started May 26 15:08:06 j1800-volumio volumio5-onboarding[1710]: time=2026-05-26T15:08:06.185+08:00 level=INFO msg="service successfully established" component=discovery/localnet May 26 15:08:06 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 26 15:08:06 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 15:08:06 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 26 15:08:12 j1800-volumio volumio[3115]: info: BOOT COMPLETED May 26 15:08:15 j1800-volumio volumio[3115]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 26 15:08:15 j1800-volumio volumio[3115]: info: Retrying Login Due to Network Error May 26 15:08:21 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetQueue May 26 15:08:21 j1800-volumio volumio[3115]: info: CoreStateMachine::getQueue May 26 15:08:21 j1800-volumio volumio[3115]: info: CorePlayQueue::getQueue May 26 15:08:21 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetVisibleSources May 26 15:08:21 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 15:08:22 j1800-volumio volumio[3115]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Piano%20Guys/Wonders/ad177495-5dfa-4d89-92e6-eee3dccb0bb7.jpg' May 26 15:08:22 j1800-volumio volumio[3115]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Piano%20Guys/Let%20It%20Go/e7ae341c-b745-4539-8143-fd0b21dfec98.jpg' May 26 15:08:22 j1800-volumio volumio[3115]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Piano%20Guys/The%20Piano%20Guys/553dd6a3-f7f2-46cf-bf7a-d8b78b54aee0.jpg' May 26 15:08:22 j1800-volumio volumio[3115]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20xx/xx/d4427bff-4548-497a-a777-292d706a5702.png' May 26 15:08:22 j1800-volumio volumio[3115]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%8E%8B%E8%B4%B0%E6%B5%AA/%E5%BE%80%E5%90%8E%E4%BD%99%E7%94%9F/eb238927-7d3a-40b2-a968-7cecdb75cf4e.jpg' May 26 15:08:23 j1800-volumio volumio[3115]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%AB%A5%E4%B8%BD/%E5%AF%B9%E8%AF%9D%E2%85%A1-%E5%8F%A4%E7%AD%9D%E4%B8%8E%E7%AB%A5%E4%B8%BD%E7%9A%84%E6%95%85%E4%BA%8B/1b9e2cda-d05e-43ea-9fc9-229329123352.jpg' May 26 15:08:23 j1800-volumio volumio[3115]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%B5%B5%E8%81%AA/%E5%8D%A1%E9%97%A8/d191600a-51db-41e0-8328-8477bc35f73c.jpg' May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioPlay May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreStateMachine::play index 6 May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreStateMachine::stop May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreStateMachine::stPlaybackTimer May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreStateMachine::updateTrackBlock May 26 15:08:24 j1800-volumio volumio[3115]: info: CorePlayQueue::getTrackBlock May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreStateMachine::pushState May 26 15:08:24 j1800-volumio volumio[3115]: info: CorePlayQueue::getTrack 0 May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioPushState May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreStateMachine::serviceStop May 26 15:08:24 j1800-volumio volumio[3115]: info: CorePlayQueue::getTrack 0 May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreCommandRouter::serviceStop May 26 15:08:24 j1800-volumio volumio[3115]: info: [1779779304532] ControllerUPNPBrowser::stop May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand stop May 26 15:08:24 j1800-volumio volumio[3115]: info: PeppyMeterBasic ---peppymeterbasic status stop May 26 15:08:24 j1800-volumio volumio[3115]: info: May 26 15:08:24 j1800-volumio volumio[3115]: ---------------------------- MPD announces state update: player May 26 15:08:24 j1800-volumio volumio[3115]: info: sendMpdCommand stop took 30 milliseconds May 26 15:08:24 j1800-volumio volumio[3115]: info: ControllerMpd::getState May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand status May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreStateMachine::play index undefined May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 15:08:24 j1800-volumio volumio[3115]: info: CorePlayQueue::getTrack 6 May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreStateMachine::startPlaybackTimer May 26 15:08:24 j1800-volumio volumio[3115]: info: CorePlayQueue::getTrack 6 May 26 15:08:24 j1800-volumio volumio[3115]: info: [1779779304572] ControllerUPNPBrowser::clearAddPlayTrack May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand stop May 26 15:08:24 j1800-volumio volumio[3115]: info: May 26 15:08:24 j1800-volumio volumio[3115]: ---------------------------- MPD announces state update: player May 26 15:08:24 j1800-volumio volumio[3115]: info: ControllerMpd::getState May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand status May 26 15:08:24 j1800-volumio volumio[3115]: info: May 26 15:08:24 j1800-volumio volumio[3115]: ---------------------------- MPD announces state update: player May 26 15:08:24 j1800-volumio volumio[3115]: info: ControllerMpd::getState May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand status May 26 15:08:24 j1800-volumio sudo[3669]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 26 15:08:24 j1800-volumio volumio[3115]: info: sendMpdCommand status took 16 milliseconds May 26 15:08:24 j1800-volumio volumio[3115]: info: sendMpdCommand stop took 10 milliseconds May 26 15:08:24 j1800-volumio volumio[3115]: info: sendMpdCommand status took 9 milliseconds May 26 15:08:24 j1800-volumio volumio[3115]: info: sendMpdCommand status took 3 milliseconds May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::parseState May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand clear May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::parseState May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::parseState May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 15:08:24 j1800-volumio sudo[3669]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:24 j1800-volumio volumio[3115]: info: May 26 15:08:24 j1800-volumio volumio[3115]: ---------------------------- MPD announces system playlist update May 26 15:08:24 j1800-volumio volumio[3115]: info: Ignoring MPD Status Update May 26 15:08:24 j1800-volumio volumio[3115]: info: sendMpdCommand playlistinfo took 12 milliseconds May 26 15:08:24 j1800-volumio volumio[3115]: info: sendMpdCommand clear took 12 milliseconds May 26 15:08:24 j1800-volumio volumio[3115]: info: sendMpdCommand playlistinfo took 13 milliseconds May 26 15:08:24 j1800-volumio volumio[3115]: info: sendMpdCommand playlistinfo took 13 milliseconds May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::parseTrackInfo May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.2.242:8200/MediaItems/1087.mp3" May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::parseTrackInfo May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::parseTrackInfo May 26 15:08:24 j1800-volumio volumio[3115]: info: ControllerMpd::pushState May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreCommandRouter::servicePushState May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreStateMachine::pushState May 26 15:08:24 j1800-volumio volumio[3115]: info: CorePlayQueue::getTrack 6 May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioPushState May 26 15:08:24 j1800-volumio volumio[3115]: info: CorePlayQueue::getTrack 6 May 26 15:08:24 j1800-volumio volumio[3115]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"FLY (加强版)","artist":"ANU","album":"FLY（加强版）","uri":"http://192.168.2.242:8200/MediaItems/1063.flac","trackType":"flac"} May 26 15:08:24 j1800-volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... May 26 15:08:24 j1800-volumio volumio[3115]: verbose: CURRENT POSITION 6 May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreStateMachine::syncState stateService stop May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreStateMachine::syncState currentStatus stop May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreStateMachine::pushState May 26 15:08:24 j1800-volumio volumio[3115]: info: CorePlayQueue::getTrack 6 May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioPushState May 26 15:08:24 j1800-volumio volumio[3115]: info: No code May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreStateMachine::pushState May 26 15:08:24 j1800-volumio volumio[3115]: info: CorePlayQueue::getTrack 6 May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioPushState May 26 15:08:24 j1800-volumio volumio[3115]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 26 15:08:24 j1800-volumio volumio[3115]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 26 15:08:24 j1800-volumio volumio[3115]: info: ------------------------------ 112ms May 26 15:08:24 j1800-volumio volumio[3115]: info: ------------------------------ 102ms May 26 15:08:24 j1800-volumio volumio[3115]: info: ------------------------------ 100ms May 26 15:08:24 j1800-volumio volumio[3115]: info: May 26 15:08:24 j1800-volumio volumio[3115]: ---------------------------- MPD announces system playlist update May 26 15:08:24 j1800-volumio volumio[3115]: info: Ignoring MPD Status Update May 26 15:08:24 j1800-volumio volumio[3115]: info: May 26 15:08:24 j1800-volumio volumio[3115]: ---------------------------- MPD announces system playlist update May 26 15:08:24 j1800-volumio volumio[3115]: info: Ignoring MPD Status Update May 26 15:08:24 j1800-volumio volumio[3115]: error: updateQueue error: null May 26 15:08:24 j1800-volumio volumio[3115]: error: updateQueue error: null May 26 15:08:24 j1800-volumio volumio[3115]: error: updateQueue error: null May 26 15:08:24 j1800-volumio volumio[3115]: info: ------------------------------ 92ms May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.2.242:8200/MediaItems/1087.mp3" May 26 15:08:24 j1800-volumio volumio[3115]: info: ------------------------------ 7ms May 26 15:08:24 j1800-volumio volumio[3115]: info: ------------------------------ 6ms May 26 15:08:24 j1800-volumio volumio[3115]: info: PeppyMeterBasic ---peppymeterbasic status stop May 26 15:08:24 j1800-volumio volumio[3115]: info: PeppyMeterBasic ---peppymeterbasic status stop May 26 15:08:24 j1800-volumio volumio[3115]: info: PeppyMeterBasic ---peppymeterbasic status stop May 26 15:08:24 j1800-volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. May 26 15:08:24 j1800-volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. May 26 15:08:24 j1800-volumio systemd[1]: peppymeterbasic.service: Consumed 5.737s CPU time. May 26 15:08:24 j1800-volumio sudo[3674]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 26 15:08:24 j1800-volumio sudo[3674]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:24 j1800-volumio sudo[3669]: pam_unix(sudo:session): session closed for user root May 26 15:08:24 j1800-volumio sudo[3676]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 26 15:08:24 j1800-volumio sudo[3676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:24 j1800-volumio volumio[3115]: info: May 26 15:08:24 j1800-volumio volumio[3115]: ---------------------------- MPD announces system playlist update May 26 15:08:24 j1800-volumio volumio[3115]: info: Ignoring MPD Status Update May 26 15:08:24 j1800-volumio volumio[3115]: info: sendMpdCommand add "http://192.168.2.242:8200/MediaItems/1087.mp3" took 129 milliseconds May 26 15:08:24 j1800-volumio volumio[3115]: info: CoreStateMachine::setConsumeUpdateService mpd May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand play May 26 15:08:24 j1800-volumio volumio[3115]: info: May 26 15:08:24 j1800-volumio volumio[3115]: ---------------------------- MPD announces system playlist update May 26 15:08:24 j1800-volumio volumio[3115]: info: Ignoring MPD Status Update May 26 15:08:24 j1800-volumio volumio[3115]: info: May 26 15:08:24 j1800-volumio volumio[3115]: ---------------------------- MPD announces system playlist update May 26 15:08:24 j1800-volumio volumio[3115]: info: Ignoring MPD Status Update May 26 15:08:24 j1800-volumio sudo[3679]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 26 15:08:24 j1800-volumio sudo[3679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:24 j1800-volumio volumio[3115]: info: peppymeterbasic Daemon Stop May 26 15:08:24 j1800-volumio volumio[3115]: info: ------------------------------ 80ms May 26 15:08:24 j1800-volumio volumio[3115]: info: sendMpdCommand play took 77 milliseconds May 26 15:08:24 j1800-volumio volumio[3115]: info: ------------------------------ 78ms May 26 15:08:24 j1800-volumio volumio[3115]: info: ------------------------------ 76ms May 26 15:08:24 j1800-volumio sudo[3674]: pam_unix(sudo:session): session closed for user root May 26 15:08:24 j1800-volumio volumio[3115]: info: May 26 15:08:24 j1800-volumio volumio[3115]: ---------------------------- MPD announces state update: player May 26 15:08:24 j1800-volumio volumio[3115]: info: ControllerMpd::getState May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand status May 26 15:08:24 j1800-volumio volumio[3115]: info: May 26 15:08:24 j1800-volumio volumio[3115]: ---------------------------- MPD announces state update: player May 26 15:08:24 j1800-volumio volumio[3115]: info: ControllerMpd::getState May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand status May 26 15:08:24 j1800-volumio volumio[3115]: info: May 26 15:08:24 j1800-volumio volumio[3115]: ---------------------------- MPD announces state update: player May 26 15:08:24 j1800-volumio volumio[3115]: info: ControllerMpd::getState May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand status May 26 15:08:24 j1800-volumio volumio[3115]: info: peppymeterbasic Daemon Stop May 26 15:08:24 j1800-volumio volumio[3115]: info: May 26 15:08:24 j1800-volumio volumio[3115]: ---------------------------- MPD announces state update: player May 26 15:08:24 j1800-volumio volumio[3115]: info: ControllerMpd::getState May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand status May 26 15:08:24 j1800-volumio volumio[3115]: info: May 26 15:08:24 j1800-volumio volumio[3115]: ---------------------------- MPD announces state update: player May 26 15:08:24 j1800-volumio volumio[3115]: info: sendMpdCommand status took 18 milliseconds May 26 15:08:24 j1800-volumio volumio[3115]: info: ControllerMpd::getState May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand status May 26 15:08:24 j1800-volumio volumio[3115]: info: sendMpdCommand status took 27 milliseconds May 26 15:08:24 j1800-volumio volumio[3115]: info: sendMpdCommand status took 21 milliseconds May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::parseState May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::parseState May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::parseState May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 15:08:24 j1800-volumio volumio[3115]: info: May 26 15:08:24 j1800-volumio volumio[3115]: ---------------------------- MPD announces state update: player May 26 15:08:24 j1800-volumio volumio[3115]: info: ControllerMpd::getState May 26 15:08:24 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand status May 26 15:08:24 j1800-volumio sudo[3676]: pam_unix(sudo:session): session closed for user root May 26 15:08:24 j1800-volumio volumio[3115]: info: May 26 15:08:24 j1800-volumio volumio[3115]: ---------------------------- MPD announces system playlist update May 26 15:08:24 j1800-volumio volumio[3115]: info: Ignoring MPD Status Update May 26 15:08:24 j1800-volumio volumio[3115]: info: May 26 15:08:24 j1800-volumio volumio[3115]: ---------------------------- MPD announces state update: player May 26 15:08:24 j1800-volumio sudo[3679]: pam_unix(sudo:session): session closed for user root May 26 15:08:25 j1800-volumio volumio[3115]: info: ControllerMpd::getState May 26 15:08:25 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand status May 26 15:08:25 j1800-volumio volumio[3115]: info: May 26 15:08:25 j1800-volumio volumio[3115]: ---------------------------- MPD announces system playlist update May 26 15:08:25 j1800-volumio volumio[3115]: info: Ignoring MPD Status Update May 26 15:08:25 j1800-volumio volumio[3115]: info: May 26 15:08:25 j1800-volumio volumio[3115]: ---------------------------- MPD announces state update: player May 26 15:08:25 j1800-volumio volumio[3115]: info: sendMpdCommand status took 74 milliseconds May 26 15:08:25 j1800-volumio volumio[3115]: info: sendMpdCommand status took 60 milliseconds May 26 15:08:25 j1800-volumio volumio[3115]: info: sendMpdCommand playlistinfo took 60 milliseconds May 26 15:08:25 j1800-volumio volumio[3115]: info: sendMpdCommand playlistinfo took 55 milliseconds May 26 15:08:25 j1800-volumio volumio[3115]: info: sendMpdCommand playlistinfo took 45 milliseconds May 26 15:08:25 j1800-volumio volumio[3115]: info: ControllerMpd::getState May 26 15:08:25 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand status May 26 15:08:25 j1800-volumio volumio[3115]: info: sendMpdCommand status took 44 milliseconds May 26 15:08:25 j1800-volumio volumio[3115]: verbose: ControllerMpd::parseState May 26 15:08:25 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 15:08:25 j1800-volumio volumio[3115]: verbose: ControllerMpd::parseState May 26 15:08:25 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 15:08:25 j1800-volumio volumio[3115]: verbose: ControllerMpd::parseTrackInfo May 26 15:08:25 j1800-volumio volumio[3115]: verbose: ControllerMpd::parseTrackInfo May 26 15:08:25 j1800-volumio volumio[3115]: verbose: ControllerMpd::parseTrackInfo May 26 15:08:25 j1800-volumio volumio[3115]: verbose: ControllerMpd::parseState May 26 15:08:25 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 15:08:25 j1800-volumio volumio[3115]: info: ControllerMpd::pushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::servicePushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CorePlayQueue::getTrack 6 May 26 15:08:25 j1800-volumio volumio[3115]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1087.mp3","artist":null,"album":null,"uri":"http://192.168.2.242:8200/MediaItems/1087.mp3","trackType":"mp3"} May 26 15:08:25 j1800-volumio volumio[3115]: verbose: CURRENT POSITION 6 May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::syncState stateService play May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::syncState currentStatus stop May 26 15:08:25 j1800-volumio volumio[3115]: info: ControllerMpd::pushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::servicePushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CorePlayQueue::getTrack 6 May 26 15:08:25 j1800-volumio volumio[3115]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1087.mp3","artist":null,"album":null,"uri":"http://192.168.2.242:8200/MediaItems/1087.mp3","trackType":"mp3"} May 26 15:08:25 j1800-volumio volumio[3115]: verbose: CURRENT POSITION 6 May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::syncState stateService play May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::syncState currentStatus play May 26 15:08:25 j1800-volumio volumio[3115]: info: Received an update from plugin. extracting info from payload May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::pushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioPushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::pushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioPushState May 26 15:08:25 j1800-volumio volumio[3115]: info: ControllerMpd::pushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::servicePushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CorePlayQueue::getTrack 6 May 26 15:08:25 j1800-volumio volumio[3115]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1087.mp3","artist":null,"album":null,"uri":"http://192.168.2.242:8200/MediaItems/1087.mp3","trackType":"mp3"} May 26 15:08:25 j1800-volumio volumio[3115]: verbose: CURRENT POSITION 6 May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::syncState stateService play May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::syncState currentStatus play May 26 15:08:25 j1800-volumio volumio[3115]: info: Received an update from plugin. extracting info from payload May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::pushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioPushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::pushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioPushState May 26 15:08:25 j1800-volumio volumio[3115]: info: ------------------------------ 154ms May 26 15:08:25 j1800-volumio volumio[3115]: info: ------------------------------ 181ms May 26 15:08:25 j1800-volumio volumio[3115]: info: ------------------------------ 181ms May 26 15:08:25 j1800-volumio volumio[3115]: info: May 26 15:08:25 j1800-volumio volumio[3115]: ---------------------------- MPD announces system playlist update May 26 15:08:25 j1800-volumio volumio[3115]: info: Ignoring MPD Status Update May 26 15:08:25 j1800-volumio volumio[3115]: info: May 26 15:08:25 j1800-volumio volumio[3115]: ---------------------------- MPD announces state update: player May 26 15:08:25 j1800-volumio volumio[3115]: info: ControllerMpd::getState May 26 15:08:25 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand status May 26 15:08:25 j1800-volumio volumio[3115]: info: peppymeterbasic Daemon Stop May 26 15:08:25 j1800-volumio volumio[3115]: info: ------------------------------ 121ms May 26 15:08:25 j1800-volumio volumio[3115]: info: sendMpdCommand status took 112 milliseconds May 26 15:08:25 j1800-volumio volumio[3115]: info: ------------------------------ 112ms May 26 15:08:25 j1800-volumio volumio[3115]: info: sendMpdCommand status took 98 milliseconds May 26 15:08:25 j1800-volumio volumio[3115]: info: sendMpdCommand playlistinfo took 94 milliseconds May 26 15:08:25 j1800-volumio volumio[3115]: info: sendMpdCommand playlistinfo took 92 milliseconds May 26 15:08:25 j1800-volumio volumio[3115]: info: sendMpdCommand playlistinfo took 81 milliseconds May 26 15:08:25 j1800-volumio volumio[3115]: info: ------------------------------ 8ms May 26 15:08:25 j1800-volumio volumio[3115]: verbose: ControllerMpd::parseState May 26 15:08:25 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 15:08:25 j1800-volumio volumio[3115]: verbose: ControllerMpd::parseState May 26 15:08:25 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 15:08:25 j1800-volumio volumio[3115]: verbose: ControllerMpd::parseTrackInfo May 26 15:08:25 j1800-volumio volumio[3115]: verbose: ControllerMpd::parseTrackInfo May 26 15:08:25 j1800-volumio volumio[3115]: verbose: ControllerMpd::parseTrackInfo May 26 15:08:25 j1800-volumio volumio[3115]: info: ControllerMpd::pushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::servicePushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CorePlayQueue::getTrack 6 May 26 15:08:25 j1800-volumio volumio[3115]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Here With You","artist":"Asher Book","album":"Here With You","uri":"http://192.168.2.242:8200/MediaItems/1087.mp3","trackType":"mp3"} May 26 15:08:25 j1800-volumio volumio[3115]: verbose: CURRENT POSITION 6 May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::syncState stateService play May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::syncState currentStatus play May 26 15:08:25 j1800-volumio volumio[3115]: info: Received an update from plugin. extracting info from payload May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::pushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioPushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::pushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioPushState May 26 15:08:25 j1800-volumio volumio[3115]: info: ControllerMpd::pushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::servicePushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CorePlayQueue::getTrack 6 May 26 15:08:25 j1800-volumio volumio[3115]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Here With You","artist":"Asher Book","album":"Here With You","uri":"http://192.168.2.242:8200/MediaItems/1087.mp3","trackType":"mp3"} May 26 15:08:25 j1800-volumio volumio[3115]: verbose: CURRENT POSITION 6 May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::syncState stateService play May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::syncState currentStatus play May 26 15:08:25 j1800-volumio volumio[3115]: info: Received an update from plugin. extracting info from payload May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::pushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioPushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::pushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioPushState May 26 15:08:25 j1800-volumio volumio[3115]: info: ControllerMpd::pushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::servicePushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CorePlayQueue::getTrack 6 May 26 15:08:25 j1800-volumio volumio[3115]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":226,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Here With You","artist":"Asher Book","album":"Here With You","uri":"http://192.168.2.242:8200/MediaItems/1087.mp3","trackType":"mp3"} May 26 15:08:25 j1800-volumio volumio[3115]: verbose: CURRENT POSITION 6 May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::syncState stateService play May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::syncState currentStatus play May 26 15:08:25 j1800-volumio volumio[3115]: info: Received an update from plugin. extracting info from payload May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::pushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioPushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::pushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioPushState May 26 15:08:25 j1800-volumio volumio[3115]: info: ------------------------------ 261ms May 26 15:08:25 j1800-volumio volumio[3115]: info: ------------------------------ 255ms May 26 15:08:25 j1800-volumio volumio[3115]: info: ------------------------------ 226ms May 26 15:08:25 j1800-volumio volumio[3115]: info: PeppyMeterBasic ---peppymeterbasic status play May 26 15:08:25 j1800-volumio volumio[3115]: info: PeppyMeterBasic ---peppymeterbasic status play May 26 15:08:25 j1800-volumio volumio[3115]: info: PeppyMeterBasic ---peppymeterbasic status play May 26 15:08:25 j1800-volumio volumio[3115]: info: PeppyMeterBasic ---peppymeterbasic status play May 26 15:08:25 j1800-volumio sudo[3685]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 26 15:08:25 j1800-volumio sudo[3685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:25 j1800-volumio volumio[3115]: info: PeppyMeterBasic ---peppymeterbasic status play May 26 15:08:25 j1800-volumio sudo[3689]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 26 15:08:25 j1800-volumio sudo[3689]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:25 j1800-volumio volumio[3115]: info: PeppyMeterBasic ---peppymeterbasic status play May 26 15:08:25 j1800-volumio sudo[3692]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 26 15:08:25 j1800-volumio sudo[3692]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:25 j1800-volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. May 26 15:08:25 j1800-volumio sudo[3685]: pam_unix(sudo:session): session closed for user root May 26 15:08:25 j1800-volumio volumio[3115]: info: PeppyMeterBasic ---peppymeterbasic status play May 26 15:08:25 j1800-volumio sudo[3689]: pam_unix(sudo:session): session closed for user root May 26 15:08:25 j1800-volumio sudo[3688]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 26 15:08:25 j1800-volumio sudo[3688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:25 j1800-volumio volumio[3115]: info: PeppyMeterBasic ---peppymeterbasic status play May 26 15:08:25 j1800-volumio sudo[3696]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 26 15:08:25 j1800-volumio volumio[3115]: info: PeppyMeterBasic ---peppymeterbasic status play May 26 15:08:25 j1800-volumio sudo[3696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:25 j1800-volumio sudo[3692]: pam_unix(sudo:session): session closed for user root May 26 15:08:25 j1800-volumio volumio[3115]: info: PeppyMeterBasic ---peppymeterbasic status play May 26 15:08:25 j1800-volumio sudo[3701]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 26 15:08:25 j1800-volumio sudo[3701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:25 j1800-volumio sudo[3704]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 26 15:08:25 j1800-volumio sudo[3704]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:25 j1800-volumio sudo[3707]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 26 15:08:25 j1800-volumio sudo[3707]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetQueue May 26 15:08:25 j1800-volumio sudo[3711]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 26 15:08:25 j1800-volumio sudo[3711]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::getQueue May 26 15:08:25 j1800-volumio volumio[3115]: info: CorePlayQueue::getQueue May 26 15:08:25 j1800-volumio sudo[3696]: pam_unix(sudo:session): session closed for user root May 26 15:08:25 j1800-volumio sudo[3704]: pam_unix(sudo:session): session closed for user root May 26 15:08:25 j1800-volumio sudo[3701]: pam_unix(sudo:session): session closed for user root May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetQueue May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::getQueue May 26 15:08:25 j1800-volumio volumio[3115]: info: CorePlayQueue::getQueue May 26 15:08:25 j1800-volumio sudo[3688]: pam_unix(sudo:session): session closed for user root May 26 15:08:25 j1800-volumio sudo[3715]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 26 15:08:25 j1800-volumio sudo[3715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:25 j1800-volumio sudo[3707]: pam_unix(sudo:session): session closed for user root May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetQueue May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::getQueue May 26 15:08:25 j1800-volumio volumio[3115]: info: CorePlayQueue::getQueue May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetQueue May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::getQueue May 26 15:08:25 j1800-volumio volumio[3115]: info: CorePlayQueue::getQueue May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetQueue May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::getQueue May 26 15:08:25 j1800-volumio sudo[3711]: pam_unix(sudo:session): session closed for user root May 26 15:08:25 j1800-volumio volumio[3115]: info: CorePlayQueue::getQueue May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetQueue May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::getQueue May 26 15:08:25 j1800-volumio volumio[3115]: info: CorePlayQueue::getQueue May 26 15:08:25 j1800-volumio sudo[3715]: pam_unix(sudo:session): session closed for user root May 26 15:08:25 j1800-volumio volumio[3115]: info: peppymeterbasic Daemon Stop May 26 15:08:25 j1800-volumio volumio[3115]: info: MyVolumio login type: Token May 26 15:08:25 j1800-volumio volumio[3115]: info: sendMpdCommand status took 744 milliseconds May 26 15:08:25 j1800-volumio volumio[3115]: info: sendMpdCommand playlistinfo took 738 milliseconds May 26 15:08:25 j1800-volumio volumio[3115]: info: sendMpdCommand playlistinfo took 738 milliseconds May 26 15:08:25 j1800-volumio volumio[3115]: verbose: ControllerMpd::parseState May 26 15:08:25 j1800-volumio volumio[3115]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 15:08:25 j1800-volumio volumio[3115]: verbose: ControllerMpd::parseTrackInfo May 26 15:08:25 j1800-volumio volumio[3115]: verbose: ControllerMpd::parseTrackInfo May 26 15:08:25 j1800-volumio volumio[3115]: info: ControllerMpd::pushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::servicePushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CorePlayQueue::getTrack 6 May 26 15:08:25 j1800-volumio volumio[3115]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":226,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Here With You","artist":"Asher Book","album":"Here With You","uri":"http://192.168.2.242:8200/MediaItems/1087.mp3","trackType":"mp3"} May 26 15:08:25 j1800-volumio volumio[3115]: verbose: CURRENT POSITION 6 May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::syncState stateService play May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::syncState currentStatus play May 26 15:08:25 j1800-volumio volumio[3115]: info: Received an update from plugin. extracting info from payload May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::pushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioPushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::pushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioPushState May 26 15:08:25 j1800-volumio volumio[3115]: info: ControllerMpd::pushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::servicePushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CorePlayQueue::getTrack 6 May 26 15:08:25 j1800-volumio volumio[3115]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":226,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Here With You","artist":"Asher Book","album":"Here With You","uri":"http://192.168.2.242:8200/MediaItems/1087.mp3","trackType":"mp3"} May 26 15:08:25 j1800-volumio volumio[3115]: verbose: CURRENT POSITION 6 May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::syncState stateService play May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::syncState currentStatus play May 26 15:08:25 j1800-volumio volumio[3115]: info: Received an update from plugin. extracting info from payload May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::pushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioPushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::pushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioPushState May 26 15:08:25 j1800-volumio volumio[3115]: info: ------------------------------ 921ms May 26 15:08:25 j1800-volumio volumio[3115]: info: ------------------------------ 910ms May 26 15:08:25 j1800-volumio volumio[3115]: info: peppymeterbasic Daemon Started May 26 15:08:25 j1800-volumio volumio[3115]: info: peppymeterbasic Daemon Started May 26 15:08:25 j1800-volumio volumio[3115]: info: peppymeterbasic Daemon Started May 26 15:08:25 j1800-volumio volumio[3115]: info: peppymeterbasic Daemon Started May 26 15:08:25 j1800-volumio volumio[3115]: info: peppymeterbasic Daemon Started May 26 15:08:25 j1800-volumio volumio[3115]: info: peppymeterbasic Daemon Started May 26 15:08:25 j1800-volumio volumio[3115]: info: peppymeterbasic Daemon Started May 26 15:08:25 j1800-volumio volumio[3115]: info: peppymeterbasic Daemon Started May 26 15:08:25 j1800-volumio volumio[3115]: info: peppymeterbasic Daemon Started May 26 15:08:25 j1800-volumio volumio[3115]: info: peppymeterbasic Daemon Started May 26 15:08:25 j1800-volumio volumio[3115]: info: sendMpdCommand playlistinfo took 103 milliseconds May 26 15:08:25 j1800-volumio volumio[3115]: verbose: ControllerMpd::parseTrackInfo May 26 15:08:25 j1800-volumio volumio[3115]: info: ControllerMpd::pushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::servicePushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CorePlayQueue::getTrack 6 May 26 15:08:25 j1800-volumio volumio[3115]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":226,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Here With You","artist":"Asher Book","album":"Here With You","uri":"http://192.168.2.242:8200/MediaItems/1087.mp3","trackType":"mp3"} May 26 15:08:25 j1800-volumio volumio[3115]: verbose: CURRENT POSITION 6 May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::syncState stateService play May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::syncState currentStatus play May 26 15:08:25 j1800-volumio volumio[3115]: info: Received an update from plugin. extracting info from payload May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::pushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioPushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreStateMachine::pushState May 26 15:08:25 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioPushState May 26 15:08:25 j1800-volumio volumio[3115]: info: ------------------------------ 885ms May 26 15:08:25 j1800-volumio volumio[3115]: info: PeppyMeterBasic ---peppymeterbasic status play May 26 15:08:26 j1800-volumio volumio[3115]: info: PeppyMeterBasic ---peppymeterbasic status play May 26 15:08:26 j1800-volumio volumio[3115]: info: PeppyMeterBasic ---peppymeterbasic status play May 26 15:08:26 j1800-volumio volumio[3115]: info: PeppyMeterBasic ---peppymeterbasic status play May 26 15:08:26 j1800-volumio sudo[3719]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 26 15:08:26 j1800-volumio sudo[3719]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:26 j1800-volumio volumio[3115]: info: PeppyMeterBasic ---peppymeterbasic status play May 26 15:08:26 j1800-volumio sudo[3721]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 26 15:08:26 j1800-volumio sudo[3721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:26 j1800-volumio volumio[3115]: info: PeppyMeterBasic ---peppymeterbasic status play May 26 15:08:26 j1800-volumio sudo[3726]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 26 15:08:26 j1800-volumio sudo[3726]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:26 j1800-volumio sudo[3723]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 26 15:08:26 j1800-volumio sudo[3723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:26 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetQueue May 26 15:08:26 j1800-volumio volumio[3115]: info: CoreStateMachine::getQueue May 26 15:08:26 j1800-volumio volumio[3115]: info: CorePlayQueue::getQueue May 26 15:08:26 j1800-volumio sudo[3719]: pam_unix(sudo:session): session closed for user root May 26 15:08:26 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetQueue May 26 15:08:26 j1800-volumio volumio[3115]: info: CoreStateMachine::getQueue May 26 15:08:26 j1800-volumio volumio[3115]: info: CorePlayQueue::getQueue May 26 15:08:26 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetQueue May 26 15:08:26 j1800-volumio volumio[3115]: info: CoreStateMachine::getQueue May 26 15:08:26 j1800-volumio volumio[3115]: info: CorePlayQueue::getQueue May 26 15:08:26 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetQueue May 26 15:08:26 j1800-volumio volumio[3115]: info: CoreStateMachine::getQueue May 26 15:08:26 j1800-volumio volumio[3115]: info: CorePlayQueue::getQueue May 26 15:08:26 j1800-volumio sudo[3733]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 26 15:08:26 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetQueue May 26 15:08:26 j1800-volumio volumio[3115]: info: CoreStateMachine::getQueue May 26 15:08:26 j1800-volumio volumio[3115]: info: CorePlayQueue::getQueue May 26 15:08:26 j1800-volumio sudo[3733]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:26 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetQueue May 26 15:08:26 j1800-volumio volumio[3115]: info: CoreStateMachine::getQueue May 26 15:08:26 j1800-volumio volumio[3115]: info: CorePlayQueue::getQueue May 26 15:08:26 j1800-volumio sudo[3726]: pam_unix(sudo:session): session closed for user root May 26 15:08:26 j1800-volumio sudo[3721]: pam_unix(sudo:session): session closed for user root May 26 15:08:26 j1800-volumio sudo[3729]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 26 15:08:26 j1800-volumio sudo[3729]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:26 j1800-volumio sudo[3723]: pam_unix(sudo:session): session closed for user root May 26 15:08:26 j1800-volumio sudo[3733]: pam_unix(sudo:session): session closed for user root May 26 15:08:26 j1800-volumio volumio[3115]: info: peppymeterbasic Daemon Started May 26 15:08:26 j1800-volumio volumio[3115]: info: peppymeterbasic Daemon Started May 26 15:08:26 j1800-volumio volumio[3115]: info: peppymeterbasic Daemon Started May 26 15:08:26 j1800-volumio volumio[3115]: info: peppymeterbasic Daemon Started May 26 15:08:26 j1800-volumio volumio[3115]: info: peppymeterbasic Daemon Started May 26 15:08:26 j1800-volumio sudo[3729]: pam_unix(sudo:session): session closed for user root May 26 15:08:26 j1800-volumio volumio[3115]: info: peppymeterbasic Daemon Started May 26 15:08:27 j1800-volumio volumio[3115]: (node:3115) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit May 26 15:08:43 j1800-volumio volumio[3115]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 15:08:43 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 26 15:08:43 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 26 15:08:43 j1800-volumio sudo[3797]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 26 15:08:43 j1800-volumio sudo[3797]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:43 j1800-volumio sudo[3797]: pam_unix(sudo:session): session closed for user root May 26 15:08:44 j1800-volumio sudo[3802]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 26 15:08:44 j1800-volumio sudo[3802]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:44 j1800-volumio sudo[3802]: pam_unix(sudo:session): session closed for user root May 26 15:08:44 j1800-volumio sudo[3809]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 26 15:08:44 j1800-volumio sudo[3809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:44 j1800-volumio sudo[3809]: pam_unix(sudo:session): session closed for user root May 26 15:08:44 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 26 15:08:44 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 26 15:08:44 j1800-volumio sudo[3816]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 26 15:08:44 j1800-volumio sudo[3816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:44 j1800-volumio sudo[3821]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 26 15:08:44 j1800-volumio sudo[3821]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:44 j1800-volumio sudo[3821]: pam_unix(sudo:session): session closed for user root May 26 15:08:44 j1800-volumio sudo[3824]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 15:08:44 j1800-volumio sudo[3824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:44 j1800-volumio sudo[3824]: pam_unix(sudo:session): session closed for user root May 26 15:08:44 j1800-volumio sudo[3816]: pam_unix(sudo:session): session closed for user root May 26 15:08:44 j1800-volumio sudo[3828]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 26 15:08:44 j1800-volumio sudo[3828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:08:45 j1800-volumio sudo[3828]: pam_unix(sudo:session): session closed for user root May 26 15:08:46 j1800-volumio volumio[3115]: info: Received Get System Info May 26 15:08:46 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 15:08:46 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 15:08:46 j1800-volumio volumio[3115]: info: Discovery: Getting this device information May 26 15:08:46 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetState May 26 15:08:46 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:08:46 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 26 15:08:46 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 26 15:08:47 j1800-volumio volumio5-onboarding[1710]: time=2026-05-26T15:08:47.117+08:00 level=INFO msg="service successfully established" component=discovery/localnet May 26 15:08:51 j1800-volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. May 26 15:08:51 j1800-volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... May 26 15:08:51 j1800-volumio systemd[1]: welcome.service: Deactivated successfully. May 26 15:08:51 j1800-volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 26 15:08:51 j1800-volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 26 15:08:51 j1800-volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 26 15:08:51 j1800-volumio welcome[3850]: Resolved ip:[1] 192.168.2.253 May 26 15:08:51 j1800-volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 26 15:08:51 j1800-volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. May 26 15:08:51 j1800-volumio volumio[3115]: info: Received Get System Info May 26 15:08:51 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 15:08:51 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 15:08:51 j1800-volumio volumio[3115]: info: Discovery: Getting this device information May 26 15:08:51 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetState May 26 15:08:51 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:08:51 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 26 15:08:51 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 26 15:08:52 j1800-volumio volumio5-onboarding[1710]: time=2026-05-26T15:08:52.381+08:00 level=INFO msg="service successfully established" component=discovery/localnet May 26 15:08:55 j1800-volumio volumio[3115]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 26 15:08:55 j1800-volumio volumio[3115]: info: Retrying Login Due to Network Error May 26 15:09:04 j1800-volumio volumio[3115]: info: CALLMETHOD: system_controller network saveWiredNet [object Object] May 26 15:09:04 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , saveWiredNet May 26 15:09:05 j1800-volumio volumio[3115]: info: MyVolumio login type: Token May 26 15:09:06 j1800-volumio volumio[3115]: info: CALLMETHOD: system_controller network saveWiredNet [object Object] May 26 15:09:06 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , saveWiredNet May 26 15:09:06 j1800-volumio sudo[3909]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces May 26 15:09:06 j1800-volumio sudo[3909]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:09:06 j1800-volumio sudo[3909]: pam_unix(sudo:session): session closed for user root May 26 15:09:06 j1800-volumio sudo[3911]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf May 26 15:09:06 j1800-volumio sudo[3911]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:09:06 j1800-volumio sudo[3911]: pam_unix(sudo:session): session closed for user root May 26 15:09:06 j1800-volumio volumio[3115]: info: Permissions for /etc/network/interfaces set May 26 15:09:06 j1800-volumio volumio[3115]: info: Restarting Wired Network May 26 15:09:06 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 26 15:09:06 j1800-volumio sudo[3914]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ip addr flush dev eth0 May 26 15:09:06 j1800-volumio sudo[3914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:09:06 j1800-volumio avahi-daemon[746]: Withdrawing address record for 192.168.2.253 on eth0. May 26 15:09:06 j1800-volumio avahi-daemon[746]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.2.253. May 26 15:09:06 j1800-volumio dhcpcd[742]: eth0: pid 3915 deleted IP address 192.168.2.253/24 May 26 15:09:06 j1800-volumio dhcpcd[742]: eth0: deleting route to 192.168.2.0/24 May 26 15:09:06 j1800-volumio dhcpcd[742]: eth0: deleting default route via 192.168.2.254 May 26 15:09:06 j1800-volumio dhcpcd[813]: eth0: pid 3915 deleted IP address 192.168.2.253/24 May 26 15:09:06 j1800-volumio dhcpcd[813]: eth0: deleting route to 192.168.2.0/24 May 26 15:09:06 j1800-volumio avahi-daemon[746]: Interface eth0.IPv4 no longer relevant for mDNS. May 26 15:09:06 j1800-volumio dhcpcd[813]: eth0: deleting default route via 192.168.2.254 May 26 15:09:06 j1800-volumio sudo[3914]: pam_unix(sudo:session): session closed for user root May 26 15:09:06 j1800-volumio volumio[3115]: info: Discovery: A device disappeared from network May 26 15:09:06 j1800-volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. May 26 15:09:06 j1800-volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... May 26 15:09:06 j1800-volumio systemd[1]: welcome.service: Deactivated successfully. May 26 15:09:06 j1800-volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 26 15:09:06 j1800-volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 26 15:09:06 j1800-volumio sudo[3919]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 down May 26 15:09:06 j1800-volumio dhcpcd[813]: eth0: rebinding lease of 192.168.2.253 May 26 15:09:06 j1800-volumio sudo[3919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:09:06 j1800-volumio dhcpcd[742]: eth0: rebinding lease of 192.168.2.253 May 26 15:09:06 j1800-volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 26 15:09:06 j1800-volumio kernel: r8169 0000:01:00.0 eth0: Link is Down May 26 15:09:06 j1800-volumio dhcpcd[813]: eth0: carrier lost May 26 15:09:06 j1800-volumio welcome[3932]: Resolved ip:[0] May 26 15:09:06 j1800-volumio sudo[3919]: pam_unix(sudo:session): session closed for user root May 26 15:09:06 j1800-volumio dhcpcd[742]: eth0: carrier lost May 26 15:09:06 j1800-volumio volumio[3115]: (node:3115) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit May 26 15:09:06 j1800-volumio volumio[3115]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 26 15:09:06 j1800-volumio volumio[3115]: info: Retrying Login Due to Network Error May 26 15:09:06 j1800-volumio volumio[3115]: info: Discovery: A device disappeared from network May 26 15:09:06 j1800-volumio volumio[3115]: info: Discovery: Device volumio-n1-sxkj disappeared from network May 26 15:09:06 j1800-volumio volumio[3115]: info: Discovery: Disconnected from remote: 192.168.2.250 May 26 15:09:06 j1800-volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 26 15:09:06 j1800-volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. May 26 15:09:06 j1800-volumio sudo[3948]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 up May 26 15:09:06 j1800-volumio kernel: Generic FE-GE Realtek PHY r8169-0-100:00: attached PHY driver (mii_bus:phy_addr=r8169-0-100:00, irq=MAC) May 26 15:09:06 j1800-volumio sudo[3948]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:09:06 j1800-volumio sudo[3948]: pam_unix(sudo:session): session closed for user root May 26 15:09:06 j1800-volumio ifplugd(eth0)[1084]: Link beat lost. May 26 15:09:06 j1800-volumio kernel: r8169 0000:01:00.0 eth0: Link is Down May 26 15:09:07 j1800-volumio volumio[3115]: info: Received Get System Info May 26 15:09:07 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 15:09:07 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 15:09:07 j1800-volumio volumio[3115]: info: Discovery: Getting this device information May 26 15:09:07 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetState May 26 15:09:07 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:09:07 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 26 15:09:07 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 26 15:09:07 j1800-volumio volumio5-onboarding[1710]: time=2026-05-26T15:09:07.988+08:00 level=INFO msg="service successfully established" component=discovery/localnet May 26 15:09:08 j1800-volumio ntpd[1061]: IO: Deleting interface #3 eth0, 192.168.2.253#123, interface stats: received=160, sent=177, dropped=2, active_time=376 secs May 26 15:09:08 j1800-volumio ntpd[1061]: PROTO: 108.59.2.24 unlink local addr 192.168.2.253 -> May 26 15:09:08 j1800-volumio ntpd[1061]: PROTO: 119.28.206.193 unlink local addr 192.168.2.253 -> May 26 15:09:08 j1800-volumio ntpd[1061]: PROTO: 114.66.25.29 unlink local addr 192.168.2.253 -> May 26 15:09:08 j1800-volumio ntpd[1061]: PROTO: 193.182.111.14 unlink local addr 192.168.2.253 -> May 26 15:09:08 j1800-volumio ntpd[1061]: PROTO: 193.182.111.141 unlink local addr 192.168.2.253 -> May 26 15:09:08 j1800-volumio ntpd[1061]: PROTO: 84.16.67.12 unlink local addr 192.168.2.253 -> May 26 15:09:08 j1800-volumio ntpd[1061]: PROTO: 139.199.214.202 unlink local addr 192.168.2.253 -> May 26 15:09:08 j1800-volumio ntpd[1061]: PROTO: 185.209.85.222 unlink local addr 192.168.2.253 -> May 26 15:09:08 j1800-volumio ntpd[1061]: PROTO: 162.159.200.123 unlink local addr 192.168.2.253 -> May 26 15:09:08 j1800-volumio ntpd[1061]: PROTO: 116.203.151.74 unlink local addr 192.168.2.253 -> May 26 15:09:08 j1800-volumio ntpd[1061]: PROTO: 119.28.183.184 unlink local addr 192.168.2.253 -> May 26 15:09:08 j1800-volumio ntpd[1061]: PROTO: 84.16.73.33 unlink local addr 192.168.2.253 -> May 26 15:09:08 j1800-volumio ntpd[1061]: PROTO: 193.182.111.143 unlink local addr 192.168.2.253 -> May 26 15:09:08 j1800-volumio ntpd[1061]: PROTO: 139.199.215.251 unlink local addr 192.168.2.253 -> May 26 15:09:09 j1800-volumio dhcpcd[813]: eth0: carrier acquired May 26 15:09:09 j1800-volumio dhcpcd[742]: eth0: carrier acquired May 26 15:09:09 j1800-volumio kernel: r8169 0000:01:00.0 eth0: Link is Up - 100Mbps/Full - flow control off May 26 15:09:09 j1800-volumio dhcpcd[813]: eth0: config file changed, expiring leases May 26 15:09:09 j1800-volumio dhcpcd[742]: eth0: config file changed, expiring leases May 26 15:09:09 j1800-volumio dhcpcd[742]: eth0: IAID 8a:03:ff:c5 May 26 15:09:09 j1800-volumio dhcpcd[742]: eth0: probing address 192.168.2.253/24 May 26 15:09:09 j1800-volumio dhcpcd[813]: eth0: IAID 8a:03:ff:c5 May 26 15:09:09 j1800-volumio dhcpcd[813]: eth0: probing address 192.168.2.253/24 May 26 15:09:09 j1800-volumio dhcpcd[813]: eth0: soliciting an IPv6 router May 26 15:09:09 j1800-volumio volumio[3115]: info: Received Get System Info May 26 15:09:09 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 15:09:09 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 15:09:09 j1800-volumio volumio[3115]: info: Discovery: Getting this device information May 26 15:09:09 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetState May 26 15:09:09 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:09:09 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 26 15:09:09 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 26 15:09:09 j1800-volumio dhcpcd[742]: eth0: soliciting an IPv6 router May 26 15:09:09 j1800-volumio ifplugd(eth0)[1084]: Link beat detected. May 26 15:09:10 j1800-volumio volumio5-onboarding[1710]: time=2026-05-26T15:09:10.441+08:00 level=INFO msg="service successfully established" component=discovery/localnet May 26 15:09:11 j1800-volumio volumio[3115]: info: Volumio Network Manager: Network status updated: 0 May 26 15:09:13 j1800-volumio avahi-daemon[746]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.2.253. May 26 15:09:13 j1800-volumio dhcpcd[813]: eth0: using static address 192.168.2.253/24 May 26 15:09:13 j1800-volumio dhcpcd[813]: eth0: adding route to 192.168.2.0/24 May 26 15:09:13 j1800-volumio avahi-daemon[746]: New relevant interface eth0.IPv4 for mDNS. May 26 15:09:13 j1800-volumio avahi-daemon[746]: Registering new address record for 192.168.2.253 on eth0.IPv4. May 26 15:09:13 j1800-volumio dhcpcd[813]: eth0: adding default route via 192.168.2.252 May 26 15:09:13 j1800-volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. May 26 15:09:13 j1800-volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... May 26 15:09:13 j1800-volumio systemd[1]: welcome.service: Deactivated successfully. May 26 15:09:13 j1800-volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 26 15:09:13 j1800-volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 26 15:09:13 j1800-volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 26 15:09:13 j1800-volumio welcome[4015]: Resolved ip:[1] 192.168.2.253 May 26 15:09:13 j1800-volumio dhcpcd[742]: eth0: using static address 192.168.2.253/24 May 26 15:09:13 j1800-volumio dhcpcd[742]: eth0: adding route to 192.168.2.0/24 May 26 15:09:13 j1800-volumio dhcpcd[742]: eth0: adding default route via 192.168.2.252 May 26 15:09:13 j1800-volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 26 15:09:13 j1800-volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. May 26 15:09:13 j1800-volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. May 26 15:09:13 j1800-volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... May 26 15:09:13 j1800-volumio systemd[1]: welcome.service: Deactivated successfully. May 26 15:09:13 j1800-volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 26 15:09:13 j1800-volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 26 15:09:13 j1800-volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 26 15:09:13 j1800-volumio volumio[3115]: info: Discovery: adding b5687ae2-c73c-4623-b0a5-f3842e3200be May 26 15:09:13 j1800-volumio volumio[3115]: info: Discovery: Found device Volumio-n1-sxkj May 26 15:09:13 j1800-volumio welcome[4045]: Resolved ip:[1] 192.168.2.253 May 26 15:09:13 j1800-volumio volumio[3115]: info: Discovery: Connecting to remote: 192.168.2.250 May 26 15:09:13 j1800-volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 26 15:09:13 j1800-volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. May 26 15:09:13 j1800-volumio volumio[3115]: info: Discovery: Connected to remote: 192.168.2.250 May 26 15:09:14 j1800-volumio volumio[3115]: info: Received Get System Info May 26 15:09:14 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 15:09:14 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 15:09:14 j1800-volumio volumio[3115]: info: Discovery: Getting this device information May 26 15:09:14 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetState May 26 15:09:14 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:09:14 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 26 15:09:14 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 26 15:09:14 j1800-volumio volumio[3115]: info: Discovery: adding a4d0fdd7-740c-42f9-abfa-873e0033aa6e May 26 15:09:14 j1800-volumio volumio[3115]: info: Discovery: Found device J1800-Volumio May 26 15:09:14 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetState May 26 15:09:15 j1800-volumio volumio5-onboarding[1710]: time=2026-05-26T15:09:15.195+08:00 level=INFO msg="service successfully established" component=discovery/localnet May 26 15:09:15 j1800-volumio ntpd[1061]: IO: Listen normally on 4 eth0 192.168.2.253:123 May 26 15:09:15 j1800-volumio ntpd[1061]: IO: new interface(s) found: waking up resolver May 26 15:09:16 j1800-volumio volumio[3115]: info: Discovery: Networking Restart detected, restarting advertisement and browsing May 26 15:09:16 j1800-volumio volumio[3115]: info: Discovery: Restarting Advertising May 26 15:09:16 j1800-volumio volumio[3115]: info: Discovery: Stopping existing advertisement May 26 15:09:16 j1800-volumio volumio[3115]: info: Discovery: Restarting Browsing May 26 15:09:16 j1800-volumio volumio[3115]: info: MyVolumio login type: Token May 26 15:09:16 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart May 26 15:09:16 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart May 26 15:09:16 j1800-volumio volumio[3115]: info: Refreshing Cached IP Addresses May 26 15:09:16 j1800-volumio sudo[4081]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli May 26 15:09:16 j1800-volumio sudo[4081]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:09:16 j1800-volumio sudo[4086]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 15:09:16 j1800-volumio sudo[4086]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:09:16 j1800-volumio sudo[4084]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 26 15:09:16 j1800-volumio sudo[4084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:09:16 j1800-volumio sudo[4086]: pam_unix(sudo:session): session closed for user root May 26 15:09:16 j1800-volumio sudo[4081]: pam_unix(sudo:session): session closed for user root May 26 15:09:16 j1800-volumio sudo[4084]: pam_unix(sudo:session): session closed for user root May 26 15:09:17 j1800-volumio sudo[4098]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 15:09:17 j1800-volumio sudo[4097]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 26 15:09:17 j1800-volumio sudo[4097]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:09:17 j1800-volumio sudo[4098]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:09:17 j1800-volumio volumio[3115]: error: MyVolumio Custom Token format not valid, refreshing it May 26 15:09:17 j1800-volumio sudo[4097]: pam_unix(sudo:session): session closed for user root May 26 15:09:17 j1800-volumio sudo[4098]: pam_unix(sudo:session): session closed for user root May 26 15:09:17 j1800-volumio volumio[3115]: verbose: New Socket.io Connection to 192.168.2.253 from 192.168.2.16 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/148.0.0.0 Safari/537.36 Edg/148.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 May 26 15:09:17 j1800-volumio volumio[3115]: info: Discovery: A device disappeared from network May 26 15:09:17 j1800-volumio volumio[3115]: info: Discovery: A device disappeared from network May 26 15:09:17 j1800-volumio volumio[3115]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 15:09:17 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetState May 26 15:09:17 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 26 15:09:17 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 26 15:09:17 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 26 15:09:17 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 26 15:09:17 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 26 15:09:17 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 26 15:09:17 j1800-volumio sudo[4106]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 26 15:09:17 j1800-volumio sudo[4106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:09:17 j1800-volumio sudo[4111]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 26 15:09:17 j1800-volumio sudo[4111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:09:17 j1800-volumio sudo[4106]: pam_unix(sudo:session): session closed for user root May 26 15:09:17 j1800-volumio sudo[4111]: pam_unix(sudo:session): session closed for user root May 26 15:09:17 j1800-volumio sudo[4118]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 26 15:09:17 j1800-volumio sudo[4118]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:09:18 j1800-volumio sudo[4122]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 26 15:09:18 j1800-volumio sudo[4122]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:09:18 j1800-volumio sudo[4118]: pam_unix(sudo:session): session closed for user root May 26 15:09:18 j1800-volumio sudo[4122]: pam_unix(sudo:session): session closed for user root May 26 15:09:18 j1800-volumio sudo[4129]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 26 15:09:18 j1800-volumio sudo[4129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:09:18 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 26 15:09:18 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 26 15:09:18 j1800-volumio sudo[4129]: pam_unix(sudo:session): session closed for user root May 26 15:09:18 j1800-volumio sudo[4133]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 15:09:18 j1800-volumio sudo[4133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:09:18 j1800-volumio sudo[4133]: pam_unix(sudo:session): session closed for user root May 26 15:09:18 j1800-volumio sudo[4137]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 26 15:09:18 j1800-volumio sudo[4137]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:09:18 j1800-volumio volumio[3115]: info: MyVolumio login type: Token May 26 15:09:19 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 15:09:19 j1800-volumio volumio[3115]: info: Received Get System Info May 26 15:09:19 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 15:09:19 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 15:09:19 j1800-volumio volumio[3115]: info: Discovery: Getting this device information May 26 15:09:19 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetState May 26 15:09:19 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:09:19 j1800-volumio sudo[4137]: pam_unix(sudo:session): session closed for user root May 26 15:09:20 j1800-volumio volumio[3115]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 26 15:09:20 j1800-volumio volumio[3115]: info: Received Get System Info May 26 15:09:20 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 15:09:20 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 15:09:20 j1800-volumio volumio[3115]: info: Discovery: Getting this device information May 26 15:09:20 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetState May 26 15:09:20 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:09:20 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 26 15:09:20 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 26 15:09:20 j1800-volumio volumio[3115]: info: MyVolumio token set successfully May 26 15:09:20 j1800-volumio volumio[3115]: info: MYVOLUMIO: Adding device May 26 15:09:20 j1800-volumio volumio[3115]: info: MYVOLUMIO: Evaluating Server May 26 15:09:21 j1800-volumio volumio5-onboarding[1710]: time=2026-05-26T15:09:21.083+08:00 level=INFO msg="service successfully established" component=discovery/localnet May 26 15:09:21 j1800-volumio volumio[3115]: info: MyVolumio status changed May 26 15:09:21 j1800-volumio volumio[3115]: info: Streaming services startup May 26 15:09:21 j1800-volumio volumio[3115]: info: Starting Streaming Daemon May 26 15:09:21 j1800-volumio volumio[3115]: info: Removing browser output: myVolumio user plan is not superstar May 26 15:09:21 j1800-volumio volumio[3115]: info: Removing audio output: May 26 15:09:21 j1800-volumio volumio[3115]: info: Stoppping Tunnel 1 May 26 15:09:21 j1800-volumio sudo[4181]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 26 15:09:21 j1800-volumio sudo[4181]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:09:21 j1800-volumio volumio[3115]: info: Volumio Network Manager: Network status updated: 1 May 26 15:09:21 j1800-volumio volumio[3115]: info: Discovery: Started advertising with name: J1800-Volumio May 26 15:09:21 j1800-volumio sudo[4178]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 26 15:09:21 j1800-volumio sudo[4178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:09:21 j1800-volumio sudo[4178]: pam_unix(sudo:session): session closed for user root May 26 15:09:21 j1800-volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 26 15:09:21 j1800-volumio volumio[3115]: error: Cannot start Volumio Streaming Daemon May 26 15:09:21 j1800-volumio volumio[3115]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 26 15:09:21 j1800-volumio volumio[3115]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 26 15:09:21 j1800-volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 26 15:09:21 j1800-volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 26 15:09:21 j1800-volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 26 15:09:21 j1800-volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 26 15:09:21 j1800-volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 26 15:09:21 j1800-volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 26 15:09:21 j1800-volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 26 15:09:21 j1800-volumio sudo[4181]: pam_unix(sudo:session): session closed for user root May 26 15:09:21 j1800-volumio volumio[3115]: info: Discovery: adding b5687ae2-c73c-4623-b0a5-f3842e3200be May 26 15:09:21 j1800-volumio volumio[3115]: info: Discovery: Found device Volumio-n1-sxkj May 26 15:09:21 j1800-volumio volumio[3115]: info: Remote SSH Stopped May 26 15:09:22 j1800-volumio volumio[3115]: info: Discovery: adding a4d0fdd7-740c-42f9-abfa-873e0033aa6e May 26 15:09:22 j1800-volumio volumio[3115]: info: Discovery: Found device J1800-Volumio May 26 15:09:22 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetState May 26 15:09:22 j1800-volumio volumio[3115]: info: Discovery: this is already registered, a4d0fdd7-740c-42f9-abfa-873e0033aa6e May 26 15:09:22 j1800-volumio volumio[3115]: info: Discovery: Found device J1800-Volumio May 26 15:09:22 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetState May 26 15:09:22 j1800-volumio volumio[3115]: info: Discovery: this is already registered, a4d0fdd7-740c-42f9-abfa-873e0033aa6e May 26 15:09:22 j1800-volumio volumio[3115]: info: Discovery: Found device J1800-Volumio May 26 15:09:22 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetState May 26 15:09:22 j1800-volumio volumio[3115]: info: Discovery: this is already registered, a4d0fdd7-740c-42f9-abfa-873e0033aa6e May 26 15:09:22 j1800-volumio volumio[3115]: info: Discovery: Found device J1800-Volumio May 26 15:09:22 j1800-volumio volumio[3115]: info: CoreCommandRouter::volumioGetState May 26 15:09:23 j1800-volumio volumio[3115]: info: Setting Geolocation for MyVolumio to eu2 May 26 15:09:23 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:09:23 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:09:23 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:09:23 j1800-volumio volumio[3115]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 26 15:09:24 j1800-volumio volumio[3115]: info: Updating MyVolumio device info May 26 15:09:24 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:09:24 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:09:24 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:09:24 j1800-volumio volumio[3115]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 26 15:09:26 j1800-volumio sudo[4197]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 26 15:09:26 j1800-volumio sudo[4197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:09:26 j1800-volumio sudo[4197]: pam_unix(sudo:session): session closed for user root May 26 15:09:26 j1800-volumio sudo[4199]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 15:09:26 j1800-volumio sudo[4199]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:09:26 j1800-volumio sudo[4199]: pam_unix(sudo:session): session closed for user root May 26 15:09:26 j1800-volumio sudo[4202]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 26 15:09:26 j1800-volumio sudo[4202]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:09:26 j1800-volumio sudo[4202]: pam_unix(sudo:session): session closed for user root May 26 15:09:26 j1800-volumio volumio[3115]: info: Upmpdcli Daemon Started May 26 15:09:27 j1800-volumio volumio[3115]: info: MYVOLUMIO: Adding device May 26 15:09:27 j1800-volumio volumio[3115]: info: MYVOLUMIO: Evaluating Server May 26 15:09:30 j1800-volumio volumio[3115]: info: Setting Geolocation for MyVolumio to eu2 May 26 15:09:30 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:09:30 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:09:30 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:09:30 j1800-volumio volumio[3115]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 26 15:09:31 j1800-volumio volumio[3115]: info: Updating MyVolumio device info May 26 15:09:31 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:09:31 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:09:31 j1800-volumio volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:09:31 j1800-volumio volumio[3115]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 26 15:09:51 j1800-volumio volumio[3115]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 15:09:51 j1800-volumio volumio[3115]: Error: connect ETIMEDOUT 104.244.43.167:443 May 26 15:09:51 j1800-volumio volumio[3115]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { May 26 15:09:51 j1800-volumio volumio[3115]: errno: -110, May 26 15:09:51 j1800-volumio volumio[3115]: code: 'ETIMEDOUT', May 26 15:09:51 j1800-volumio volumio[3115]: syscall: 'connect', May 26 15:09:51 j1800-volumio volumio[3115]: address: '104.244.43.167', May 26 15:09:51 j1800-volumio volumio[3115]: port: 443 May 26 15:09:51 j1800-volumio volumio[3115]: } May 26 15:09:51 j1800-volumio volumio[3115]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 15:09:52 j1800-volumio sudo[4306]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-26 15:08' May 26 15:09:52 j1800-volumio sudo[4306]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"