-- Logs begin at Tue 2025-11-04 10:09:37 PST, end at Tue 2025-11-04 18:17:39 PST. -- Nov 04 18:16:00 volumio-nanopi volumio[1077]: verbose: New Socket.io Connection to 192.168.254.100:3000 from 192.168.254.114 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Nov 04 18:16:00 volumio-nanopi volumio[1077]: verbose: New Socket.io Connection to 192.168.254.100:3000 from 192.168.254.114 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Nov 04 18:16:04 volumio-nanopi volumio[1077]: verbose: New Socket.io Connection to 192.168.254.100 from 192.168.254.114 UA: Mozilla/5.0 (Linux; Android 14; T614D Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Nov 04 18:16:04 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 04 18:16:04 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioGetVisibleSources Nov 04 18:16:04 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 04 18:16:04 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 04 18:16:04 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 04 18:16:04 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 04 18:16:04 volumio-nanopi volumio[1077]: info: Received Get System Info Nov 04 18:16:04 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 04 18:16:04 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 04 18:16:04 volumio-nanopi volumio[1077]: info: Discovery: Getting this device information Nov 04 18:16:04 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 04 18:16:04 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 04 18:16:04 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 04 18:16:04 volumio-nanopi volumio[1077]: info: Listing playlists Nov 04 18:16:04 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioGetQueue Nov 04 18:16:04 volumio-nanopi volumio[1077]: info: CoreStateMachine::getQueue Nov 04 18:16:04 volumio-nanopi volumio[1077]: info: CorePlayQueue::getQueue Nov 04 18:16:04 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 04 18:16:04 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:05 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:25 volumio-nanopi volumio-remote-updater[711]: [2025-11-04 18:16:25] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Nov 04 18:16:30 volumio-nanopi volumio-remote-updater[711]: [2025-11-04 18:16:30] [connect] Successful connection Nov 04 18:16:38 volumio-nanopi volumio[1077]: verbose: New Socket.io Connection to 192.168.254.100:3000 from 192.168.254.114 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Nov 04 18:16:38 volumio-nanopi volumio[1077]: verbose: New Socket.io Connection to 192.168.254.100:3000 from 192.168.254.114 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Nov 04 18:16:38 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 04 18:16:38 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 04 18:16:38 volumio-nanopi volumio[1077]: info: Discovery: Getting this device information Nov 04 18:16:38 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 04 18:16:38 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 04 18:16:39 volumio-nanopi volumio[1077]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 04 18:16:39 volumio-nanopi volumio[1077]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Nov 04 18:16:39 volumio-nanopi volumio[1077]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Nov 04 18:16:39 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 04 18:16:42 volumio-nanopi volumio-remote-updater[711]: [2025-11-04 18:16:42] [connect] Successful connection Nov 04 18:16:42 volumio-nanopi volumio-remote-updater[711]: [2025-11-04 18:16:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1762309002 101 Nov 04 18:16:42 volumio-nanopi volumio[1077]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 6 Nov 04 18:16:50 volumio-nanopi volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 04 18:16:50 volumio-nanopi volumio[1077]: info: CorePlayQueue::getTrack 1 Nov 04 18:16:50 volumio-nanopi volumio[1077]: info: Prefetching next song Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: [1762309016183] ControllerPandora::pandoraListener Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: ControllerMpd::getState Nov 04 18:16:56 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: Nov 04 18:16:56 volumio-nanopi volumio[1077]: ---------------------------- MPD announces state update: player Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: ControllerMpd::getState Nov 04 18:16:56 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: Nov 04 18:16:56 volumio-nanopi volumio[1077]: ---------------------------- MPD announces state update: player Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: ControllerMpd::getState Nov 04 18:16:56 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: sendMpdCommand status took 33 milliseconds Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: sendMpdCommand status took 17 milliseconds Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: sendMpdCommand status took 10 milliseconds Nov 04 18:16:56 volumio-nanopi volumio[1077]: verbose: ControllerMpd::parseState Nov 04 18:16:56 volumio-nanopi volumio[1077]: verbose: ControllerMpd::parseState Nov 04 18:16:56 volumio-nanopi volumio[1077]: verbose: ControllerMpd::parseState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: [1762309016242] ControllerPandora::pushState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreCommandRouter::servicePushState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreStateMachine::pushState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 04 18:16:56 volumio-nanopi volumio[1077]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null,"trackType":"mp3"} Nov 04 18:16:56 volumio-nanopi volumio[1077]: verbose: CURRENT POSITION 0 Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreStateMachine::syncState stateService stop Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreStateMachine::syncState currentStatus play Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreStateMachine::play index undefined Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreStateMachine::pushState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CorePlayQueue::getTrack 1 Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreStateMachine::setConsumeUpdateService pandora Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: ControllerMpd::pushState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreCommandRouter::servicePushState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreStateMachine::pushState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CorePlayQueue::getTrack 1 Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: ControllerMpd::pushState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreCommandRouter::servicePushState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreStateMachine::pushState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CorePlayQueue::getTrack 1 Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CorePlayQueue::getTrack 1 Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreStateMachine::startPlaybackTimer Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CorePlayQueue::getTrack 1 Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: [1762309016389] ControllerPandora::clearAddPlayTrack Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: ControllerMpd::clear Nov 04 18:16:56 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand clear Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: ------------------------------ 205ms Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: ------------------------------ 188ms Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreStateMachine::pushState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: Nov 04 18:16:56 volumio-nanopi volumio[1077]: ---------------------------- MPD announces state update: player Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: ControllerMpd::getState Nov 04 18:16:56 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: Nov 04 18:16:56 volumio-nanopi volumio[1077]: ---------------------------- MPD announces state update: player Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: ControllerMpd::getState Nov 04 18:16:56 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: Nov 04 18:16:56 volumio-nanopi volumio[1077]: ---------------------------- MPD announces system playlist update Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: Ignoring MPD Status Update Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: Nov 04 18:16:56 volumio-nanopi volumio[1077]: ---------------------------- MPD announces system playlist update Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: Ignoring MPD Status Update Nov 04 18:16:56 volumio-nanopi volumio[1077]: error: updateQueue error: null Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: sendMpdCommand clear took 78 milliseconds Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: sendMpdCommand status took 39 milliseconds Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: sendMpdCommand status took 34 milliseconds Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: ------------------------------ 21ms Nov 04 18:16:56 volumio-nanopi volumio[1077]: verbose: ControllerMpd::parseState Nov 04 18:16:56 volumio-nanopi volumio[1077]: verbose: ControllerMpd::parseState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: [1762309016490] ControllerPandora::checkForExpiredStations Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: [1762309016492] [Pandora] checkForExpiredStations: Stations expired Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: [1762309016495] ControllerPandora::PandoraHandler::fillStationData Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: ControllerMpd::pushState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreCommandRouter::servicePushState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreStateMachine::pushState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CorePlayQueue::getTrack 1 Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: ControllerMpd::pushState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreCommandRouter::servicePushState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreStateMachine::pushState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CorePlayQueue::getTrack 1 Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: ------------------------------ 183ms Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: ------------------------------ 179ms Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: Nov 04 18:16:56 volumio-nanopi volumio[1077]: ---------------------------- MPD announces system playlist update Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: Ignoring MPD Status Update Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: Nov 04 18:16:56 volumio-nanopi volumio[1077]: ---------------------------- MPD announces system playlist update Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: Ignoring MPD Status Update Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: FusionDsp - volume level for loudness 73 gain applied 0.00 Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: FusionDsp - Loudness is ON true Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: FusionDsp - volume level for loudness 73 gain applied 0.00 Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: FusionDsp - Loudness is ON true Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: FusionDsp - volume level for loudness 73 gain applied 0.00 Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: FusionDsp - Loudness is ON true Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: FusionDsp - volume level for loudness 73 gain applied 0.00 Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: FusionDsp - Loudness is ON true Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: FusionDsp - volume level for loudness 73 gain applied 0.00 Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: FusionDsp - Loudness is ON true Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: FusionDsp - volume level for loudness 73 gain applied 0.00 Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: FusionDsp - Loudness is ON true Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: FusionDsp - volume level for loudness 73 gain applied 0.00 Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: FusionDsp - Loudness is ON true Nov 04 18:16:56 volumio-nanopi volumio[1077]: error: updateQueue error: null Nov 04 18:16:56 volumio-nanopi volumio[1077]: error: updateQueue error: null Nov 04 18:16:56 volumio-nanopi volumio[1077]: error: updateQueue error: null Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: ------------------------------ 412ms Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: ------------------------------ 258ms Nov 04 18:16:56 volumio-nanopi volumio[1077]: info: ------------------------------ 254ms Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: camilladsp spawned new process with pid 2629, instance 1, run: true Nov 04 18:16:57 volumio-nanopi volumio[1077]: error: FusionDsp - WebSocket error: [object Object] Nov 04 18:16:57 volumio-nanopi volumio[1077]: error: FusionDsp - WebSocket error: [object Object] Nov 04 18:16:57 volumio-nanopi volumio[1077]: error: FusionDsp - WebSocket error: [object Object] Nov 04 18:16:57 volumio-nanopi volumio[1077]: error: FusionDsp - WebSocket error: [object Object] Nov 04 18:16:57 volumio-nanopi volumio[1077]: error: FusionDsp - WebSocket error: [object Object] Nov 04 18:16:57 volumio-nanopi volumio[1077]: error: FusionDsp - WebSocket error: [object Object] Nov 04 18:16:57 volumio-nanopi volumio[1077]: error: FusionDsp - WebSocket error: [object Object] Nov 04 18:16:57 volumio-nanopi volumio[1077]: verbose: New Socket.io Connection to 192.168.254.100 from 192.168.254.114 UA: Mozilla/5.0 (Linux; Android 14; T614D Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CorePlayQueue::getQueue Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: [1762309017338] ControllerPandora::removeTrack Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreStateMachine::removeQueueItem Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CorePlayQueue::removeQueueItem 0 Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CorePlayQueue::saveQueue Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioPushQueue Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: [1762309017372] [Pandora] removeTrack: Removed track with uri: /pandora/stationToken=2984030234797564708trackId=7941025063403776326 at queue index: 0 Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: ControllerMpd::getState Nov 04 18:16:57 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: [1762309017410] ControllerPandora::appendTracksToMpd Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: [1762309017414] [Pandora] appendTracksToMpd: Added 1 track(s) to mpd Nov 04 18:16:57 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand addid Nov 04 18:16:57 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand play Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: Nov 04 18:16:57 volumio-nanopi volumio[1077]: ---------------------------- MPD announces system playlist update Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: Ignoring MPD Status Update Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: sendMpdCommand status took 227 milliseconds Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: sendMpdCommand addid took 181 milliseconds Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: sendMpdCommand play took 181 milliseconds Nov 04 18:16:57 volumio-nanopi volumio[1077]: verbose: ControllerMpd::parseState Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: [1762309017616] ControllerPandora::pushState Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreCommandRouter::servicePushState Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreStateMachine::pushState Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 04 18:16:57 volumio-nanopi volumio[1077]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null,"trackType":"mp3"} Nov 04 18:16:57 volumio-nanopi volumio[1077]: verbose: CURRENT POSITION 0 Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreStateMachine::syncState stateService stop Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreStateMachine::syncState currentStatus stop Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreStateMachine::pushState Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: No code Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreStateMachine::pushState Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreStateMachine::setConsumeUpdateService pandora Nov 04 18:16:57 volumio-nanopi volumio[1077]: verbose: MPD COMMAND [object Object] Nov 04 18:16:57 volumio-nanopi volumio[1077]: verbose: MPD COMMAND [object Object] Nov 04 18:16:57 volumio-nanopi volumio[1077]: verbose: MPD COMMAND [object Object] Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: [1762309017666] ControllerPandora::pushState Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreCommandRouter::servicePushState Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 04 18:16:57 volumio-nanopi volumio[1077]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"Moonlight","name":"Moonlight","artist":"Nel Aique","album":"Moonlight","albumart":"http://mediaserver-cont-usc-mp1-2-v4v6.pandora.com/images/77/0a/cf/dd/055c4323a6041d6cce97ed25/1080W_1080H.jpg","realUri":"http://t1-3.p-cdn.us/access/1521191837665441798.mp3?version=5&lid=1075995428&token=LvcwEvh6JJpcuenMFzBEdbGujvNhfsp3irDM0VviLM1J7JInRfCo2jD3rNCLhz7dIzoCpLW%2BrjKbQEaqBJ4oOTVy2PvjMy0ZqmgNkX%2F2GilXPxtF2Tq5GQfTBoOK%2FpYUgjbnNgBu7Ek2hIIcNE84ZGC22jQ67CYCzzUOeHllJhVTaAp8mqjuVku5%2Bjb5Zp1izW%2BCS1ChqabN8anQwsUvR%2FPWNN7DxuciFdSnoaj%2B%2BuGTiAf0qrIojPPLB8KhXkCvsTXspGpcIvS0ZC1c%2FhqcUd6mLuZ7OGvZZ49GD38KTn44WJrLk5gaPisuQR6iLDyGQ4lidhsiUVfncCuVqiTmQjC34tQCz8sg","isStreaming":true,"duration":183,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://t1-3.p-cdn.us/access/1521191837665441798.mp3?version=5&lid=1075995428&token=LvcwEvh6JJpcuenMFzBEdbGujvNhfsp3irDM0VviLM1J7JInRfCo2jD3rNCLhz7dIzoCpLW%2BrjKbQEaqBJ4oOTVy2PvjMy0ZqmgNkX%2F2GilXPxtF2Tq5GQfTBoOK%2FpYUgjbnNgBu7Ek2hIIcNE84ZGC22jQ67CYCzzUOeHllJhVTaAp8mqjuVku5%2Bjb5Zp1izW%2BCS1ChqabN8anQwsUvR%2FPWNN7DxuciFdSnoaj%2B%2BuGTiAf0qrIojPPLB8KhXkCvsTXspGpcIvS0ZC1c%2FhqcUd6mLuZ7OGvZZ49GD38KTn44WJrLk5gaPisuQR6iLDyGQ4lidhsiUVfncCuVqiTmQjC34tQCz8sg","seek":0,"status":"play"} Nov 04 18:16:57 volumio-nanopi volumio[1077]: verbose: CURRENT POSITION 0 Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreStateMachine::syncState stateService play Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreStateMachine::syncState currentStatus stop Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreStateMachine::setConsumeUpdateService pandora Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: [1762309017704] ControllerPandora::fetchAndAddTracks Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: [1762309017707] ControllerPandora::getStationTracks Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CorePlayQueue::getQueue Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: [1762309017713] [Pandora] PandoraHandler::getSongMaxDiff: -1 Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: [1762309017717] ControllerPandora::fetchAndAddTracks::getSqInfo Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: [1762309017718] ControllerPandora::getStationTracks Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CorePlayQueue::getQueue Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CorePlayQueue::getQueue Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: [1762309017726] [Pandora] fetchAndAddTracks: diff1: -1 sQPos1: 0 Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: [1762309017727] [Pandora] fetchAndAddTracks: Fetching tracks Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: [1762309017731] ControllerPandora::PandoraHandler::fillStationData Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: Nov 04 18:16:57 volumio-nanopi volumio[1077]: ---------------------------- MPD announces system playlist update Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: Ignoring MPD Status Update Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: Nov 04 18:16:57 volumio-nanopi volumio[1077]: ---------------------------- MPD announces system playlist update Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: Ignoring MPD Status Update Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: Nov 04 18:16:57 volumio-nanopi volumio[1077]: ---------------------------- MPD announces system playlist update Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: Ignoring MPD Status Update Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioGetVisibleSources Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: Received Get System Info Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: Discovery: Getting this device information Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioGetState Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: Listing playlists Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioGetQueue Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CoreStateMachine::getQueue Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: CorePlayQueue::getQueue Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: FusionDsp - volume level for loudness 73 gain applied 0.00 Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: FusionDsp - Loudness is ON true Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: FusionDsp - volume level for loudness 73 gain applied 0.00 Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: FusionDsp - Loudness is ON true Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: FusionDsp - volume level for loudness 73 gain applied 0.00 Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 04 18:16:57 volumio-nanopi volumio[1077]: info: FusionDsp - Loudness is ON true Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: Nov 04 18:16:58 volumio-nanopi volumio[1077]: ---------------------------- MPD announces system playlist update Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: Ignoring MPD Status Update Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ------------------------------ 446ms Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ------------------------------ 304ms Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ------------------------------ 313ms Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ------------------------------ 310ms Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: Nov 04 18:16:58 volumio-nanopi volumio[1077]: ---------------------------- MPD announces system playlist update Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: Ignoring MPD Status Update Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: Nov 04 18:16:58 volumio-nanopi volumio[1077]: ---------------------------- MPD announces system playlist update Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: Ignoring MPD Status Update Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: Nov 04 18:16:58 volumio-nanopi volumio[1077]: ---------------------------- MPD announces system playlist update Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: Ignoring MPD Status Update Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ------------------------------ 271ms Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ------------------------------ 242ms Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ------------------------------ 235ms Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ------------------------------ 230ms Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: [1762309018478] ControllerPandora::pandoraListener Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ControllerMpd::getState Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: Nov 04 18:16:58 volumio-nanopi volumio[1077]: ---------------------------- MPD announces state update: player Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ControllerMpd::getState Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: Nov 04 18:16:58 volumio-nanopi volumio[1077]: ---------------------------- MPD announces state update: player Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ControllerMpd::getState Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: Nov 04 18:16:58 volumio-nanopi volumio[1077]: ---------------------------- MPD announces state update: player Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ControllerMpd::getState Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: Nov 04 18:16:58 volumio-nanopi volumio[1077]: ---------------------------- MPD announces state update: player Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ControllerMpd::getState Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: Nov 04 18:16:58 volumio-nanopi volumio[1077]: ---------------------------- MPD announces state update: player Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ControllerMpd::getState Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: sendMpdCommand status took 48 milliseconds Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: sendMpdCommand status took 41 milliseconds Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: sendMpdCommand status took 36 milliseconds Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: sendMpdCommand status took 28 milliseconds Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: sendMpdCommand status took 23 milliseconds Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::parseState Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::parseState Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::parseState Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::parseState Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::parseState Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: Nov 04 18:16:58 volumio-nanopi volumio[1077]: ---------------------------- MPD announces state update: player Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ControllerMpd::getState Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: Nov 04 18:16:58 volumio-nanopi volumio[1077]: ---------------------------- MPD announces state update: player Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ControllerMpd::getState Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: Nov 04 18:16:58 volumio-nanopi volumio[1077]: ---------------------------- MPD announces state update: player Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ControllerMpd::getState Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: FusionDsp - Loudness is ON true Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: [1762309018659] ControllerPandora::PandoraHandler::fetchTracks::fetchStationPlaylist Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: sendMpdCommand status took 169 milliseconds Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: sendMpdCommand playlistinfo took 154 milliseconds Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: sendMpdCommand playlistinfo took 153 milliseconds Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: sendMpdCommand playlistinfo took 152 milliseconds Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: sendMpdCommand playlistinfo took 151 milliseconds Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: sendMpdCommand playlistinfo took 150 milliseconds Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: sendMpdCommand status took 139 milliseconds Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: sendMpdCommand status took 132 milliseconds Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: sendMpdCommand status took 127 milliseconds Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::parseState Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::parseState Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::parseState Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::parseState Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: [1762309018750] ControllerPandora::pushState Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CoreCommandRouter::servicePushState Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":183,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Moonlight","artist":"Nel Aique","album":"Moonlight","uri":"http://t1-3.p-cdn.us/access/1521191837665441798.mp3?version=5&lid=1075995428&token=LvcwEvh6JJpcuenMFzBEdbGujvNhfsp3irDM0VviLM1J7JInRfCo2jD3rNCLhz7dIzoCpLW%2BrjKbQEaqBJ4oOTVy2PvjMy0ZqmgNkX%2F2GilXPxtF2Tq5GQfTBoOK%2FpYUgjbnNgBu7Ek2hIIcNE84ZGC22jQ67CYCzzUOeHllJhVTaAp8mqjuVku5%2Bjb5Zp1izW%2BCS1ChqabN8anQwsUvR%2FPWNN7DxuciFdSnoaj%2B%2BuGTiAf0qrIojPPLB8KhXkCvsTXspGpcIvS0ZC1c%2FhqcUd6mLuZ7OGvZZ49GD38KTn44WJrLk5gaPisuQR6iLDyGQ4lidhsiUVfncCuVqiTmQjC34tQCz8sg","trackType":"mp3"} Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: CURRENT POSITION 0 Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CoreStateMachine::syncState stateService play Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CoreStateMachine::syncState currentStatus play Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: Received an update from plugin. extracting info from payload Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CoreStateMachine::pushState Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CoreStateMachine::pushState Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioPushState Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CoreStateMachine::setConsumeUpdateService pandora Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ControllerMpd::pushState Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CoreCommandRouter::servicePushState Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ControllerMpd::pushState Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CoreCommandRouter::servicePushState Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ControllerMpd::pushState Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CoreCommandRouter::servicePushState Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ControllerMpd::pushState Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CoreCommandRouter::servicePushState Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ------------------------------ 348ms Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ------------------------------ 338ms Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ------------------------------ 330ms Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ------------------------------ 324ms Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: sendMpdCommand playlistinfo took 192 milliseconds Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: sendMpdCommand playlistinfo took 175 milliseconds Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: sendMpdCommand playlistinfo took 173 milliseconds Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: sendMpdCommand playlistinfo took 172 milliseconds Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 04 18:16:58 volumio-nanopi volumio[1077]: verbose: ControllerMpd::parseTrackInfo Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ControllerMpd::pushState Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CoreCommandRouter::servicePushState Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ControllerMpd::pushState Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CoreCommandRouter::servicePushState Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ControllerMpd::pushState Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CoreCommandRouter::servicePushState Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ControllerMpd::pushState Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CoreCommandRouter::servicePushState Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CorePlayQueue::getTrack 0 Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ------------------------------ 422ms Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ------------------------------ 379ms Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ------------------------------ 371ms Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: ------------------------------ 364ms Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CoreCommandRouter::volumioGetQueue Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CoreStateMachine::getQueue Nov 04 18:16:58 volumio-nanopi volumio[1077]: info: CorePlayQueue::getQueue Nov 04 18:16:59 volumio-nanopi volumio[1077]: info: FusionDsp - volume level for loudness 73 gain applied 0.00 Nov 04 18:16:59 volumio-nanopi volumio[1077]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 04 18:16:59 volumio-nanopi volumio[1077]: info: FusionDsp - Loudness is ON true Nov 04 18:16:59 volumio-nanopi volumio[1077]: info: FusionDsp - volume level for loudness 73 gain applied 0.00 Nov 04 18:16:59 volumio-nanopi volumio[1077]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 04 18:16:59 volumio-nanopi volumio[1077]: info: FusionDsp - Loudness is ON true Nov 04 18:16:59 volumio-nanopi volumio[1077]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 04 18:17:00 volumio-nanopi volumio[1077]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 04 18:17:12 volumio-nanopi volumio-remote-updater[711]: [2025-11-04 18:17:12] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Nov 04 18:17:17 volumio-nanopi volumio-remote-updater[711]: [2025-11-04 18:17:17] [connect] Successful connection Nov 04 18:17:30 volumio-nanopi volumio-remote-updater[711]: [2025-11-04 18:17:30] [connect] Successful connection Nov 04 18:17:35 volumio-nanopi volumio[1077]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 18:17:35 volumio-nanopi volumio[1077]: SyntaxError: Unexpected end of JSON input Nov 04 18:17:35 volumio-nanopi volumio[1077]: at JSON.parse () Nov 04 18:17:35 volumio-nanopi volumio[1077]: at Request._callback (/data/plugins/music_service/pandora/node_modules/anesidora/anesidora.js:41:31) Nov 04 18:17:35 volumio-nanopi volumio[1077]: at Request.self.callback (/data/plugins/music_service/pandora/node_modules/request/request.js:185:22) Nov 04 18:17:35 volumio-nanopi volumio[1077]: at Request.emit (events.js:400:28) Nov 04 18:17:35 volumio-nanopi volumio[1077]: at Request. (/data/plugins/music_service/pandora/node_modules/request/request.js:1154:10) Nov 04 18:17:35 volumio-nanopi volumio[1077]: at Request.emit (events.js:400:28) Nov 04 18:17:35 volumio-nanopi volumio[1077]: at IncomingMessage. (/data/plugins/music_service/pandora/node_modules/request/request.js:1076:12) Nov 04 18:17:35 volumio-nanopi volumio[1077]: at Object.onceWrapper (events.js:519:28) Nov 04 18:17:35 volumio-nanopi volumio[1077]: at IncomingMessage.emit (events.js:412:35) Nov 04 18:17:35 volumio-nanopi volumio[1077]: at IncomingMessage. (/data/plugins/music_service/pandora/node_modules/request/request.js:993:23) Nov 04 18:17:35 volumio-nanopi volumio[1077]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 18:17:39 volumio-nanopi sudo[2773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-04 18:16 Nov 04 18:17:39 volumio-nanopi sudo[2773]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 29 Jul 2025 12:40:52 PM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="orangepipc" VOLUMIO_DEVICENAME="Orange Pi PC" VOLUMIO_HASH="053d26d2b7f23be800db334519309821"