-- Logs begin at Fri 2024-11-22 17:35:38 HKT, end at Fri 2024-11-22 18:09:18 HKT. -- Nov 22 18:08:41 volumio volumio[864]: info: CorePlayQueue::getTrack 11 Nov 22 18:08:41 volumio volumio[864]: info: CorePlayQueue::getTrack 12 Nov 22 18:08:47 volumio volumio[864]: info: Nov 22 18:08:47 volumio volumio[864]: ---------------------------- MPD announces system playlist update Nov 22 18:08:47 volumio volumio[864]: info: Ignoring MPD Status Update Nov 22 18:08:47 volumio volumio[864]: info: Nov 22 18:08:47 volumio volumio[864]: ---------------------------- MPD announces state update: player Nov 22 18:08:47 volumio volumio[864]: info: ControllerMpd::getState Nov 22 18:08:47 volumio volumio[864]: verbose: ControllerMpd::sendMpdCommand status Nov 22 18:08:47 volumio volumio[864]: info: Nov 22 18:08:47 volumio volumio[864]: ---------------------------- MPD announces system playlist update Nov 22 18:08:47 volumio volumio[864]: info: Ignoring MPD Status Update Nov 22 18:08:47 volumio volumio[864]: info: Nov 22 18:08:47 volumio volumio[864]: ---------------------------- MPD announces state update: player Nov 22 18:08:47 volumio volumio[864]: info: ControllerMpd::getState Nov 22 18:08:47 volumio volumio[864]: verbose: ControllerMpd::sendMpdCommand status Nov 22 18:08:47 volumio volumio[864]: info: Nov 22 18:08:47 volumio volumio[864]: ---------------------------- MPD announces system playlist update Nov 22 18:08:47 volumio volumio[864]: info: Ignoring MPD Status Update Nov 22 18:08:47 volumio volumio[864]: info: Nov 22 18:08:47 volumio volumio[864]: ---------------------------- MPD announces state update: player Nov 22 18:08:47 volumio volumio[864]: info: ControllerMpd::getState Nov 22 18:08:47 volumio volumio[864]: verbose: ControllerMpd::sendMpdCommand status Nov 22 18:08:47 volumio volumio[864]: info: Nov 22 18:08:47 volumio volumio[864]: ---------------------------- MPD announces system playlist update Nov 22 18:08:47 volumio volumio[864]: info: Ignoring MPD Status Update Nov 22 18:08:47 volumio volumio[864]: info: Nov 22 18:08:47 volumio volumio[864]: ---------------------------- MPD announces state update: player Nov 22 18:08:47 volumio volumio[864]: info: ControllerMpd::getState Nov 22 18:08:47 volumio volumio[864]: verbose: ControllerMpd::sendMpdCommand status Nov 22 18:08:47 volumio volumio[864]: error: updateQueue error: null Nov 22 18:08:47 volumio volumio[864]: error: updateQueue error: null Nov 22 18:08:47 volumio volumio[864]: info: ------------------------------ 5ms Nov 22 18:08:47 volumio volumio[864]: info: sendMpdCommand status took 4 milliseconds Nov 22 18:08:47 volumio volumio[864]: info: ------------------------------ 4ms Nov 22 18:08:47 volumio volumio[864]: info: sendMpdCommand status took 3 milliseconds Nov 22 18:08:47 volumio volumio[864]: info: ------------------------------ 3ms Nov 22 18:08:47 volumio volumio[864]: info: sendMpdCommand status took 2 milliseconds Nov 22 18:08:47 volumio volumio[864]: info: ------------------------------ 3ms Nov 22 18:08:47 volumio volumio[864]: info: sendMpdCommand status took 2 milliseconds Nov 22 18:08:47 volumio volumio[864]: verbose: ControllerMpd::parseState Nov 22 18:08:47 volumio volumio[864]: verbose: ControllerMpd::parseState Nov 22 18:08:47 volumio volumio[864]: verbose: ControllerMpd::parseState Nov 22 18:08:47 volumio volumio[864]: verbose: ControllerMpd::parseState Nov 22 18:08:47 volumio volumio[864]: info: ControllerMpd::pushState Nov 22 18:08:47 volumio volumio[864]: info: CoreCommandRouter::servicePushState Nov 22 18:08:47 volumio volumio[864]: info: CoreStateMachine::pushState Nov 22 18:08:47 volumio volumio[864]: info: CorePlayQueue::getTrack 11 Nov 22 18:08:47 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 18:08:47 volumio volumio[864]: info: CoreCommandRouter::volumioPushState Nov 22 18:08:47 volumio volumio[864]: info: CorePlayQueue::getTrack 11 Nov 22 18:08:47 volumio volumio[864]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 22 18:08:47 volumio volumio[864]: verbose: CURRENT POSITION 11 Nov 22 18:08:47 volumio volumio[864]: info: CoreStateMachine::syncState stateService stop Nov 22 18:08:47 volumio volumio[864]: info: CoreStateMachine::syncState currentStatus play Nov 22 18:08:47 volumio volumio[864]: info: CoreCommandRouter::volumioPushState Nov 22 18:08:47 volumio volumio[864]: info: CoreStateMachine::stPlaybackTimer Nov 22 18:08:47 volumio volumio[864]: info: ControllerMpd::pushState Nov 22 18:08:47 volumio volumio[864]: info: CoreCommandRouter::servicePushState Nov 22 18:08:47 volumio volumio[864]: info: CoreStateMachine::pushState Nov 22 18:08:47 volumio volumio[864]: info: CorePlayQueue::getTrack 0 Nov 22 18:08:47 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 18:08:47 volumio volumio[864]: info: CoreCommandRouter::volumioPushState Nov 22 18:08:47 volumio volumio[864]: info: CorePlayQueue::getTrack 0 Nov 22 18:08:47 volumio volumio[864]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 22 18:08:47 volumio volumio[864]: verbose: CURRENT POSITION 0 Nov 22 18:08:47 volumio volumio[864]: info: CoreStateMachine::syncState stateService stop Nov 22 18:08:47 volumio volumio[864]: info: CoreStateMachine::syncState currentStatus stop Nov 22 18:08:47 volumio volumio[864]: info: CoreStateMachine::pushState Nov 22 18:08:47 volumio volumio[864]: info: CorePlayQueue::getTrack 0 Nov 22 18:08:47 volumio volumio[864]: info: CoreCommandRouter::volumioPushState Nov 22 18:08:47 volumio volumio[864]: info: No code Nov 22 18:08:47 volumio volumio[864]: info: CoreStateMachine::pushState Nov 22 18:08:47 volumio volumio[864]: info: CorePlayQueue::getTrack 0 Nov 22 18:08:47 volumio volumio[864]: info: CoreCommandRouter::volumioPushState Nov 22 18:08:47 volumio volumio[864]: info: ControllerMpd::pushState Nov 22 18:08:47 volumio volumio[864]: info: CoreCommandRouter::servicePushState Nov 22 18:08:47 volumio volumio[864]: info: CoreStateMachine::pushState Nov 22 18:08:47 volumio volumio[864]: info: CorePlayQueue::getTrack 0 Nov 22 18:08:47 volumio volumio[864]: info: CoreCommandRouter::volumioPushState Nov 22 18:08:47 volumio volumio[864]: info: CorePlayQueue::getTrack 0 Nov 22 18:08:47 volumio volumio[864]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 22 18:08:47 volumio volumio[864]: verbose: CURRENT POSITION 0 Nov 22 18:08:47 volumio volumio[864]: info: CoreStateMachine::syncState stateService stop Nov 22 18:08:47 volumio volumio[864]: info: CoreStateMachine::syncState currentStatus stop Nov 22 18:08:47 volumio volumio[864]: info: CoreStateMachine::pushState Nov 22 18:08:47 volumio volumio[864]: info: CorePlayQueue::getTrack 0 Nov 22 18:08:47 volumio volumio[864]: info: CoreCommandRouter::volumioPushState Nov 22 18:08:47 volumio volumio[864]: info: No code Nov 22 18:08:47 volumio volumio[864]: info: CoreStateMachine::pushState Nov 22 18:08:47 volumio volumio[864]: info: CorePlayQueue::getTrack 0 Nov 22 18:08:47 volumio volumio[864]: info: CoreCommandRouter::volumioPushState Nov 22 18:08:47 volumio volumio[864]: info: ControllerMpd::pushState Nov 22 18:08:47 volumio volumio[864]: info: CoreCommandRouter::servicePushState Nov 22 18:08:47 volumio volumio[864]: info: CoreStateMachine::pushState Nov 22 18:08:47 volumio volumio[864]: info: CorePlayQueue::getTrack 0 Nov 22 18:08:47 volumio volumio[864]: info: CoreCommandRouter::volumioPushState Nov 22 18:08:47 volumio volumio[864]: info: CorePlayQueue::getTrack 0 Nov 22 18:08:47 volumio volumio[864]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 22 18:08:47 volumio volumio[864]: verbose: CURRENT POSITION 0 Nov 22 18:08:47 volumio volumio[864]: info: CoreStateMachine::syncState stateService stop Nov 22 18:08:47 volumio volumio[864]: info: CoreStateMachine::syncState currentStatus stop Nov 22 18:08:47 volumio volumio[864]: info: CoreStateMachine::pushState Nov 22 18:08:47 volumio volumio[864]: info: CorePlayQueue::getTrack 0 Nov 22 18:08:47 volumio volumio[864]: info: CoreCommandRouter::volumioPushState Nov 22 18:08:47 volumio volumio[864]: info: No code Nov 22 18:08:47 volumio volumio[864]: info: CoreStateMachine::pushState Nov 22 18:08:47 volumio volumio[864]: info: CorePlayQueue::getTrack 0 Nov 22 18:08:47 volumio volumio[864]: info: CoreCommandRouter::volumioPushState Nov 22 18:08:47 volumio volumio[864]: info: ------------------------------ 33ms Nov 22 18:08:47 volumio volumio[864]: info: ------------------------------ 32ms Nov 22 18:08:47 volumio volumio[864]: info: ------------------------------ 32ms Nov 22 18:08:47 volumio volumio[864]: info: ------------------------------ 31ms Nov 22 18:08:48 volumio sudo[4888]: pam_unix(sudo:session): session closed for user root Nov 22 18:08:59 volumio volumio[864]: info: CoreCommandRouter::volumioGetState Nov 22 18:08:59 volumio volumio[864]: info: CorePlayQueue::getTrack 0 Nov 22 18:08:59 volumio screensaver.sh[588]: 4863 Nov 22 18:08:59 volumio volumio[864]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Nov 22 18:08:59 volumio volumio[864]: No protocol specified Nov 22 18:08:59 volumio volumio[864]: Exception in thread Thread-4: Nov 22 18:08:59 volumio volumio[864]: Traceback (most recent call last): Nov 22 18:08:59 volumio volumio[864]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Nov 22 18:08:59 volumio volumio[864]: self.run() Nov 22 18:08:59 volumio volumio[864]: File "/usr/lib/python3.7/threading.py", line 865, in run Nov 22 18:08:59 volumio volumio[864]: self._target(*self._args, **self._kwargs) Nov 22 18:08:59 volumio volumio[864]: File "./screensaver/volumio_peppymeter.py", line 109, in meter_thread Nov 22 18:08:59 volumio volumio[864]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/peppymeter/peppymeter.py", line 144, in init_display Nov 22 18:08:59 volumio volumio[864]: pygame.display.init() Nov 22 18:08:59 volumio volumio[864]: pygame.error: Unable to open a console terminal Nov 22 18:08:59 volumio volumio[864]: Exception in thread Thread-3: Nov 22 18:08:59 volumio volumio[864]: Traceback (most recent call last): Nov 22 18:08:59 volumio volumio[864]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Nov 22 18:08:59 volumio volumio[864]: self.run() Nov 22 18:08:59 volumio volumio[864]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_random.py", line 135, in run Nov 22 18:08:59 volumio volumio[864]: socketIO.wait(1) Nov 22 18:08:59 volumio volumio[864]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 251, in wait Nov 22 18:08:59 volumio volumio[864]: self._process_packets() Nov 22 18:08:59 volumio volumio[864]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 278, in _process_packets Nov 22 18:08:59 volumio volumio[864]: self._process_packet(engineIO_packet) Nov 22 18:08:59 volumio volumio[864]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 491, in _process_packet Nov 22 18:08:59 volumio volumio[864]: delegate(parse_socketIO_packet_data(socketIO_packet_data), namespace) Nov 22 18:08:59 volumio volumio[864]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 513, in _on_event Nov 22 18:08:59 volumio volumio[864]: namespace._find_packet_callback(event)(*args) Nov 22 18:08:59 volumio volumio[864]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_random.py", line 106, in on_push_state Nov 22 18:08:59 volumio volumio[864]: meter_restart() # own restart fadeIn Nov 22 18:08:59 volumio volumio[864]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_random.py", line 51, in meter_restart Nov 22 18:08:59 volumio volumio[864]: screen = self.meter.util.PYGAME_SCREEN Nov 22 18:08:59 volumio volumio[864]: AttributeError: 'MeterUtil' object has no attribute 'PYGAME_SCREEN' Nov 22 18:08:59 volumio volumio[864]: Terminated Nov 22 18:09:10 volumio volumio[864]: info: CoreCommandRouter::volumioGetState Nov 22 18:09:10 volumio volumio[864]: info: CorePlayQueue::getTrack 0 Nov 22 18:09:16 volumio volumio[864]: verbose: New Socket.io Connection to 192.168.199.203 from 192.168.199.117 UA: okhttp/4.9.2 Total Clients: 6 Nov 22 18:09:16 volumio volumio[864]: verbose: New Socket.io Connection to 192.168.199.203 from 192.168.199.117 UA: okhttp/4.9.2 Total Clients: 7 Nov 22 18:09:16 volumio volumio[864]: info: CoreCommandRouter::volumioGetState Nov 22 18:09:16 volumio volumio[864]: info: CorePlayQueue::getTrack 0 Nov 22 18:09:16 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 22 18:09:16 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 22 18:09:16 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 22 18:09:16 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 22 18:09:16 volumio volumio[864]: info: CoreCommandRouter::volumioGetVisibleSources Nov 22 18:09:16 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 18:09:16 volumio volumio[864]: info: CoreCommandRouter::volumioGetState Nov 22 18:09:16 volumio volumio[864]: info: CorePlayQueue::getTrack 0 Nov 22 18:09:16 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 22 18:09:16 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 22 18:09:16 volumio volumio[864]: info: Received Get System Info Nov 22 18:09:16 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 18:09:16 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 18:09:16 volumio volumio[864]: info: Discovery: Getting this device information Nov 22 18:09:16 volumio volumio[864]: info: CoreCommandRouter::volumioGetState Nov 22 18:09:16 volumio volumio[864]: info: CorePlayQueue::getTrack 0 Nov 22 18:09:16 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 18:09:16 volumio volumio[864]: info: CoreCommandRouter::volumioGetState Nov 22 18:09:16 volumio volumio[864]: info: CorePlayQueue::getTrack 0 Nov 22 18:09:16 volumio volumio[864]: info: Listing playlists Nov 22 18:09:16 volumio volumio[864]: info: CoreCommandRouter::volumioGetQueue Nov 22 18:09:16 volumio volumio[864]: info: CoreStateMachine::getQueue Nov 22 18:09:16 volumio volumio[864]: info: CorePlayQueue::getQueue Nov 22 18:09:16 volumio volumio[864]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 22 18:09:18 volumio volumio[864]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 22 18:09:18 volumio volumio[864]: Error: connect ECONNREFUSED 108.160.170.44:80 Nov 22 18:09:18 volumio volumio[864]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Nov 22 18:09:18 volumio volumio[864]: errno: -111, Nov 22 18:09:18 volumio volumio[864]: code: 'ECONNREFUSED', Nov 22 18:09:18 volumio volumio[864]: syscall: 'connect', Nov 22 18:09:18 volumio volumio[864]: address: '108.160.170.44', Nov 22 18:09:18 volumio volumio[864]: port: 80 Nov 22 18:09:18 volumio volumio[864]: } Nov 22 18:09:18 volumio volumio[864]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 22 18:09:18 volumio sudo[7656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-22 18:08 Nov 22 18:09:18 volumio sudo[7656]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"