May 31 21:23:09 volumio-rpi kernel: CIFS: VFS: \\192.168.1.38 Invalid mid allocation time May 31 21:23:18 volumio-rpi volumio[1234]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 31 21:23:18 volumio-rpi volumio[1234]: info: Retrying Login Due to Network Error May 31 21:23:26 volumio-rpi volumio[1234]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: unknown Engine version: 4 Transport: websocket Total Clients: 10 May 31 21:23:26 volumio-rpi volumio[1234]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: unknown Engine version: 4 Transport: websocket Total Clients: 10 May 31 21:23:26 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioGetState May 31 21:23:26 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 0 May 31 21:23:26 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 31 21:23:26 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioGetQueue May 31 21:23:26 volumio-rpi volumio[1234]: info: CoreStateMachine::getQueue May 31 21:23:26 volumio-rpi volumio[1234]: info: CorePlayQueue::getQueue May 31 21:23:28 volumio-rpi volumio[1234]: info: MyVolumio login type: Token May 31 21:23:30 volumio-rpi volumio[1234]: (node:1234) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit May 31 21:23:35 volumio-rpi volumio[1234]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: unknown Engine version: 4 Transport: websocket Total Clients: 10 May 31 21:23:35 volumio-rpi volumio[1234]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: unknown Engine version: 4 Transport: websocket Total Clients: 10 May 31 21:23:35 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioGetState May 31 21:23:35 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 0 May 31 21:23:35 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 31 21:23:35 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioGetQueue May 31 21:23:35 volumio-rpi volumio[1234]: info: CoreStateMachine::getQueue May 31 21:23:35 volumio-rpi volumio[1234]: info: CorePlayQueue::getQueue May 31 21:23:58 volumio-rpi volumio[1234]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 31 21:23:58 volumio-rpi volumio[1234]: info: Retrying Login Due to Network Error May 31 21:24:01 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 0 May 31 21:24:01 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 1 May 31 21:24:01 volumio-rpi volumio[1234]: info: Prefetching next song May 31 21:24:01 volumio-rpi volumio[1234]: info: DOING PREFETCH IN MPD May 31 21:24:03 volumio-rpi volumio[1234]: verbose: ControllerMpd::sendMpdCommand add "USB/EAGET忆捷/2.原唱/央金兰泽.-.[爱琴海]/. 布达拉.wav" May 31 21:24:03 volumio-rpi volumio[1234]: info: May 31 21:24:03 volumio-rpi volumio[1234]: ---------------------------- MPD announces system playlist update May 31 21:24:03 volumio-rpi volumio[1234]: info: Ignoring MPD Status Update May 31 21:24:03 volumio-rpi volumio[1234]: info: sendMpdCommand add "USB/EAGET忆捷/2.原唱/央金兰泽.-.[爱琴海]/. 布达拉.wav" took 27 milliseconds May 31 21:24:03 volumio-rpi volumio[1234]: verbose: ControllerMpd::sendMpdCommand consume 1 May 31 21:24:03 volumio-rpi volumio[1234]: info: May 31 21:24:03 volumio-rpi volumio[1234]: ---------------------------- MPD announces system playlist update May 31 21:24:03 volumio-rpi volumio[1234]: info: Ignoring MPD Status Update May 31 21:24:03 volumio-rpi volumio[1234]: info: May 31 21:24:03 volumio-rpi volumio[1234]: ---------------------------- MPD announces system playlist update May 31 21:24:03 volumio-rpi volumio[1234]: info: Ignoring MPD Status Update May 31 21:24:03 volumio-rpi volumio[1234]: info: May 31 21:24:03 volumio-rpi volumio[1234]: ---------------------------- MPD announces state update: options May 31 21:24:03 volumio-rpi volumio[1234]: info: ------------------------------ 82ms May 31 21:24:03 volumio-rpi volumio[1234]: info: sendMpdCommand consume 1 took 79 milliseconds May 31 21:24:03 volumio-rpi volumio[1234]: info: ------------------------------ 79ms May 31 21:24:03 volumio-rpi volumio[1234]: info: ------------------------------ 77ms May 31 21:24:03 volumio-rpi volumio[1234]: info: ControllerMpd::getState May 31 21:24:03 volumio-rpi volumio[1234]: verbose: ControllerMpd::sendMpdCommand status May 31 21:24:03 volumio-rpi volumio[1234]: info: May 31 21:24:03 volumio-rpi volumio[1234]: ---------------------------- MPD announces state update: options May 31 21:24:03 volumio-rpi volumio[1234]: info: ControllerMpd::getState May 31 21:24:03 volumio-rpi volumio[1234]: verbose: ControllerMpd::sendMpdCommand status May 31 21:24:03 volumio-rpi volumio[1234]: info: May 31 21:24:03 volumio-rpi volumio[1234]: ---------------------------- MPD announces state update: options May 31 21:24:03 volumio-rpi volumio[1234]: info: ControllerMpd::getState May 31 21:24:03 volumio-rpi volumio[1234]: verbose: ControllerMpd::sendMpdCommand status May 31 21:24:03 volumio-rpi volumio[1234]: info: sendMpdCommand status took 12 milliseconds May 31 21:24:03 volumio-rpi volumio[1234]: verbose: ControllerMpd::parseState May 31 21:24:03 volumio-rpi volumio[1234]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 21:24:03 volumio-rpi volumio[1234]: info: sendMpdCommand status took 50 milliseconds May 31 21:24:03 volumio-rpi volumio[1234]: info: sendMpdCommand status took 46 milliseconds May 31 21:24:03 volumio-rpi volumio[1234]: verbose: ControllerMpd::parseState May 31 21:24:03 volumio-rpi volumio[1234]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 21:24:03 volumio-rpi volumio[1234]: verbose: ControllerMpd::parseState May 31 21:24:03 volumio-rpi volumio[1234]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 21:24:03 volumio-rpi volumio[1234]: info: sendMpdCommand playlistinfo took 4 milliseconds May 31 21:24:03 volumio-rpi volumio[1234]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 21:24:03 volumio-rpi volumio[1234]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 21:24:03 volumio-rpi volumio[1234]: verbose: ControllerMpd::parseTrackInfo May 31 21:24:03 volumio-rpi volumio[1234]: verbose: ControllerMpd::parseTrackInfo May 31 21:24:03 volumio-rpi volumio[1234]: verbose: ControllerMpd::parseTrackInfo May 31 21:24:03 volumio-rpi volumio[1234]: info: ControllerMpd::pushState May 31 21:24:03 volumio-rpi volumio[1234]: info: CoreCommandRouter::servicePushState May 31 21:24:03 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 0 May 31 21:24:03 volumio-rpi volumio[1234]: verbose: STATE SERVICE {"status":"play","position":0,"seek":237825,"duration":239,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"????","artist":"????","album":"???","uri":"USB/EAGET忆捷/2.原唱/央金兰泽.-.[爱琴海]/. 守望白云.wav","trackType":"wav"} May 31 21:24:03 volumio-rpi volumio[1234]: verbose: CURRENT POSITION 0 May 31 21:24:03 volumio-rpi volumio[1234]: info: CoreStateMachine::syncState stateService play May 31 21:24:03 volumio-rpi volumio[1234]: info: CoreStateMachine::syncState currentStatus play May 31 21:24:03 volumio-rpi volumio[1234]: info: Received an update from plugin. extracting info from payload May 31 21:24:03 volumio-rpi volumio[1234]: info: CoreStateMachine::pushState May 31 21:24:03 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 0 May 31 21:24:03 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 21:24:03 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioPushState May 31 21:24:03 volumio-rpi volumio[1234]: info: CoreStateMachine::pushState May 31 21:24:03 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 0 May 31 21:24:03 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioPushState May 31 21:24:03 volumio-rpi volumio[1234]: info: ControllerMpd::pushState May 31 21:24:03 volumio-rpi volumio[1234]: info: CoreCommandRouter::servicePushState May 31 21:24:03 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 0 May 31 21:24:03 volumio-rpi volumio[1234]: verbose: STATE SERVICE {"status":"play","position":0,"seek":237825,"duration":239,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"????","artist":"????","album":"???","uri":"USB/EAGET忆捷/2.原唱/央金兰泽.-.[爱琴海]/. 守望白云.wav","trackType":"wav"} May 31 21:24:03 volumio-rpi volumio[1234]: verbose: CURRENT POSITION 0 May 31 21:24:03 volumio-rpi volumio[1234]: info: CoreStateMachine::syncState stateService play May 31 21:24:03 volumio-rpi volumio[1234]: info: CoreStateMachine::syncState currentStatus play May 31 21:24:03 volumio-rpi volumio[1234]: info: Received an update from plugin. extracting info from payload May 31 21:24:03 volumio-rpi volumio[1234]: info: CoreStateMachine::pushState May 31 21:24:03 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 0 May 31 21:24:03 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioPushState May 31 21:24:03 volumio-rpi volumio[1234]: info: CoreStateMachine::pushState May 31 21:24:03 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 0 May 31 21:24:03 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioPushState May 31 21:24:03 volumio-rpi volumio[1234]: info: ControllerMpd::pushState May 31 21:24:03 volumio-rpi volumio[1234]: info: CoreCommandRouter::servicePushState May 31 21:24:03 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 0 May 31 21:24:03 volumio-rpi volumio[1234]: verbose: STATE SERVICE {"status":"play","position":0,"seek":237825,"duration":239,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"????","artist":"????","album":"???","uri":"USB/EAGET忆捷/2.原唱/央金兰泽.-.[爱琴海]/. 守望白云.wav","trackType":"wav"} May 31 21:24:03 volumio-rpi volumio[1234]: verbose: CURRENT POSITION 0 May 31 21:24:03 volumio-rpi volumio[1234]: info: CoreStateMachine::syncState stateService play May 31 21:24:03 volumio-rpi volumio[1234]: info: CoreStateMachine::syncState currentStatus play May 31 21:24:03 volumio-rpi volumio[1234]: info: Received an update from plugin. extracting info from payload May 31 21:24:03 volumio-rpi volumio[1234]: info: CoreStateMachine::pushState May 31 21:24:03 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 0 May 31 21:24:03 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioPushState May 31 21:24:03 volumio-rpi volumio[1234]: info: CoreStateMachine::pushState May 31 21:24:03 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 0 May 31 21:24:03 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioPushState May 31 21:24:03 volumio-rpi volumio[1234]: info: ------------------------------ 411ms May 31 21:24:03 volumio-rpi volumio[1234]: info: ------------------------------ 407ms May 31 21:24:03 volumio-rpi volumio[1234]: info: ------------------------------ 399ms May 31 21:24:03 volumio-rpi volumio[1234]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 31 21:24:04 volumio-rpi volumio[1234]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 31 21:24:04 volumio-rpi volumio[1234]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 31 21:24:04 volumio-rpi volumio[1234]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 31 21:24:04 volumio-rpi volumio[1234]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 31 21:24:04 volumio-rpi volumio[1234]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 31 21:24:04 volumio-rpi volumio[1234]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 21:24:04 volumio-rpi volumio[1234]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 21:24:04 volumio-rpi volumio[1234]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 21:24:04 volumio-rpi volumio[1234]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 21:24:04 volumio-rpi volumio[1234]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 21:24:04 volumio-rpi volumio[1234]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 21:24:05 volumio-rpi volumio[1234]: info: May 31 21:24:05 volumio-rpi volumio[1234]: ---------------------------- MPD announces system playlist update May 31 21:24:05 volumio-rpi volumio[1234]: info: Ignoring MPD Status Update May 31 21:24:05 volumio-rpi volumio[1234]: info: May 31 21:24:05 volumio-rpi volumio[1234]: ---------------------------- MPD announces state update: player May 31 21:24:05 volumio-rpi volumio[1234]: info: ControllerMpd::getState May 31 21:24:05 volumio-rpi volumio[1234]: verbose: ControllerMpd::sendMpdCommand status May 31 21:24:05 volumio-rpi volumio[1234]: info: May 31 21:24:05 volumio-rpi volumio[1234]: ---------------------------- MPD announces system playlist update May 31 21:24:05 volumio-rpi volumio[1234]: info: Ignoring MPD Status Update May 31 21:24:05 volumio-rpi volumio[1234]: info: May 31 21:24:05 volumio-rpi volumio[1234]: ---------------------------- MPD announces state update: player May 31 21:24:05 volumio-rpi volumio[1234]: info: ControllerMpd::getState May 31 21:24:05 volumio-rpi volumio[1234]: verbose: ControllerMpd::sendMpdCommand status May 31 21:24:05 volumio-rpi volumio[1234]: info: May 31 21:24:05 volumio-rpi volumio[1234]: ---------------------------- MPD announces system playlist update May 31 21:24:05 volumio-rpi volumio[1234]: info: Ignoring MPD Status Update May 31 21:24:05 volumio-rpi volumio[1234]: info: May 31 21:24:05 volumio-rpi volumio[1234]: ---------------------------- MPD announces state update: player May 31 21:24:05 volumio-rpi volumio[1234]: info: ControllerMpd::getState May 31 21:24:05 volumio-rpi volumio[1234]: verbose: ControllerMpd::sendMpdCommand status May 31 21:24:05 volumio-rpi volumio[1234]: info: ------------------------------ 15ms May 31 21:24:05 volumio-rpi volumio[1234]: info: sendMpdCommand status took 13 milliseconds May 31 21:24:05 volumio-rpi volumio[1234]: info: ------------------------------ 13ms May 31 21:24:05 volumio-rpi volumio[1234]: info: sendMpdCommand status took 9 milliseconds May 31 21:24:05 volumio-rpi volumio[1234]: info: ------------------------------ 8ms May 31 21:24:05 volumio-rpi volumio[1234]: info: sendMpdCommand status took 5 milliseconds May 31 21:24:05 volumio-rpi volumio[1234]: verbose: ControllerMpd::parseState May 31 21:24:05 volumio-rpi volumio[1234]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 21:24:05 volumio-rpi volumio[1234]: verbose: ControllerMpd::parseState May 31 21:24:05 volumio-rpi volumio[1234]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 21:24:05 volumio-rpi volumio[1234]: verbose: ControllerMpd::parseState May 31 21:24:05 volumio-rpi volumio[1234]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 21:24:05 volumio-rpi volumio[1234]: info: sendMpdCommand playlistinfo took 7 milliseconds May 31 21:24:05 volumio-rpi volumio[1234]: info: sendMpdCommand playlistinfo took 5 milliseconds May 31 21:24:05 volumio-rpi volumio[1234]: info: sendMpdCommand playlistinfo took 6 milliseconds May 31 21:24:05 volumio-rpi volumio[1234]: verbose: ControllerMpd::parseTrackInfo May 31 21:24:05 volumio-rpi volumio[1234]: verbose: ControllerMpd::parseTrackInfo May 31 21:24:05 volumio-rpi volumio[1234]: verbose: ControllerMpd::parseTrackInfo May 31 21:24:05 volumio-rpi volumio[1234]: info: ControllerMpd::pushState May 31 21:24:05 volumio-rpi volumio[1234]: info: CoreCommandRouter::servicePushState May 31 21:24:05 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 0 May 31 21:24:05 volumio-rpi volumio[1234]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"???","artist":"????","album":"???","uri":"USB/EAGET忆捷/2.原唱/央金兰泽.-.[爱琴海]/. 布达拉.wav","trackType":"wav"} May 31 21:24:05 volumio-rpi volumio[1234]: verbose: CURRENT POSITION 0 May 31 21:24:05 volumio-rpi volumio[1234]: info: CoreStateMachine::syncState stateService play May 31 21:24:05 volumio-rpi volumio[1234]: info: CoreStateMachine::syncState currentStatus play May 31 21:24:05 volumio-rpi volumio[1234]: info: Received an update from plugin. extracting info from payload May 31 21:24:05 volumio-rpi volumio[1234]: info: CoreStateMachine::pushState May 31 21:24:05 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 0 May 31 21:24:05 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 21:24:05 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioPushState May 31 21:24:05 volumio-rpi volumio[1234]: info: CoreStateMachine::pushState May 31 21:24:05 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 0 May 31 21:24:05 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioPushState May 31 21:24:05 volumio-rpi volumio[1234]: info: ControllerMpd::pushState May 31 21:24:05 volumio-rpi volumio[1234]: info: CoreCommandRouter::servicePushState May 31 21:24:05 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 0 May 31 21:24:05 volumio-rpi volumio[1234]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"???","artist":"????","album":"???","uri":"USB/EAGET忆捷/2.原唱/央金兰泽.-.[爱琴海]/. 布达拉.wav","trackType":"wav"} May 31 21:24:05 volumio-rpi volumio[1234]: verbose: CURRENT POSITION 0 May 31 21:24:05 volumio-rpi volumio[1234]: info: CoreStateMachine::syncState stateService play May 31 21:24:05 volumio-rpi volumio[1234]: info: CoreStateMachine::syncState currentStatus play May 31 21:24:05 volumio-rpi volumio[1234]: info: Received an update from plugin. extracting info from payload May 31 21:24:05 volumio-rpi volumio[1234]: info: CoreStateMachine::pushState May 31 21:24:05 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 0 May 31 21:24:05 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioPushState May 31 21:24:05 volumio-rpi volumio[1234]: info: CoreStateMachine::pushState May 31 21:24:05 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 0 May 31 21:24:05 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioPushState May 31 21:24:05 volumio-rpi volumio[1234]: info: ControllerMpd::pushState May 31 21:24:05 volumio-rpi volumio[1234]: info: CoreCommandRouter::servicePushState May 31 21:24:05 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 0 May 31 21:24:05 volumio-rpi volumio[1234]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"???","artist":"????","album":"???","uri":"USB/EAGET忆捷/2.原唱/央金兰泽.-.[爱琴海]/. 布达拉.wav","trackType":"wav"} May 31 21:24:05 volumio-rpi volumio[1234]: verbose: CURRENT POSITION 0 May 31 21:24:05 volumio-rpi volumio[1234]: info: CoreStateMachine::syncState stateService play May 31 21:24:05 volumio-rpi volumio[1234]: info: CoreStateMachine::syncState currentStatus play May 31 21:24:05 volumio-rpi volumio[1234]: info: Received an update from plugin. extracting info from payload May 31 21:24:05 volumio-rpi volumio[1234]: info: CoreStateMachine::pushState May 31 21:24:05 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 0 May 31 21:24:05 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioPushState May 31 21:24:05 volumio-rpi volumio[1234]: info: CoreStateMachine::pushState May 31 21:24:05 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 0 May 31 21:24:05 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioPushState May 31 21:24:05 volumio-rpi volumio[1234]: info: ------------------------------ 236ms May 31 21:24:05 volumio-rpi volumio[1234]: info: ------------------------------ 233ms May 31 21:24:05 volumio-rpi volumio[1234]: info: ------------------------------ 231ms May 31 21:24:05 volumio-rpi volumio[1234]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 31 21:24:05 volumio-rpi volumio[1234]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 31 21:24:05 volumio-rpi volumio[1234]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 31 21:24:05 volumio-rpi volumio[1234]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 31 21:24:05 volumio-rpi volumio[1234]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 31 21:24:05 volumio-rpi volumio[1234]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 31 21:24:05 volumio-rpi volumio[1234]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 21:24:05 volumio-rpi volumio[1234]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 21:24:05 volumio-rpi volumio[1234]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 21:24:05 volumio-rpi volumio[1234]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 21:24:05 volumio-rpi volumio[1234]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 21:24:05 volumio-rpi volumio[1234]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 21:24:05 volumio-rpi volumio[1234]: info: CoreStateMachine::startPlaybackTimer May 31 21:24:05 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 1 May 31 21:24:06 volumio-rpi volumio[1234]: info: CoreStateMachine::pushState May 31 21:24:06 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 1 May 31 21:24:06 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 21:24:06 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioPushState May 31 21:24:06 volumio-rpi volumio[1234]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false May 31 21:24:06 volumio-rpi volumio[1234]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 21:24:08 volumio-rpi volumio[1234]: info: MyVolumio login type: Token May 31 21:24:10 volumio-rpi volumio[1234]: (node:1234) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit May 31 21:24:19 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 21:24:19 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 21:24:19 volumio-rpi volumio[1234]: info: Discovery: Getting this device information May 31 21:24:19 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioGetState May 31 21:24:19 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 1 May 31 21:24:19 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 21:24:19 volumio-rpi volumio[1234]: verbose: New Socket.io Connection to 192.168.1.18 from 192.168.1.4 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 11 May 31 21:24:19 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioGetState May 31 21:24:19 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 1 May 31 21:24:19 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 31 21:24:19 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 31 21:24:22 volumio-rpi sudo[3295]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 21:24:22 volumio-rpi sudo[3298]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 21:24:22 volumio-rpi sudo[3298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 21:24:22 volumio-rpi sudo[3295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 21:24:22 volumio-rpi sudo[3295]: pam_unix(sudo:session): session closed for user root May 31 21:24:22 volumio-rpi sudo[3298]: pam_unix(sudo:session): session closed for user root May 31 21:24:22 volumio-rpi volumio[1234]: verbose: New Socket.io Connection to 192.168.1.18 from 192.168.1.4 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 12 May 31 21:24:22 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 21:24:23 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioGetVisibleSources May 31 21:24:23 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 21:24:23 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioGetState May 31 21:24:23 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 1 May 31 21:24:23 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 31 21:24:23 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 31 21:24:23 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 21:24:24 volumio-rpi volumio[1234]: info: Received Get System Info May 31 21:24:24 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 21:24:24 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 21:24:24 volumio-rpi volumio[1234]: info: Discovery: Getting this device information May 31 21:24:24 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioGetState May 31 21:24:24 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 1 May 31 21:24:24 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 21:24:24 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioGetState May 31 21:24:24 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 1 May 31 21:24:24 volumio-rpi volumio[1234]: info: Listing playlists May 31 21:24:24 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 31 21:24:24 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 21:24:24 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 21:24:24 volumio-rpi volumio[1234]: info: Received Get System Info May 31 21:24:24 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 21:24:24 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 21:24:24 volumio-rpi volumio[1234]: info: Discovery: Getting this device information May 31 21:24:24 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioGetState May 31 21:24:24 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 1 May 31 21:24:24 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 21:24:25 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 31 21:24:30 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioGetQueue May 31 21:24:30 volumio-rpi volumio[1234]: info: CoreStateMachine::getQueue May 31 21:24:30 volumio-rpi volumio[1234]: info: CorePlayQueue::getQueue May 31 21:24:32 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 31 21:24:35 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 21:24:35 volumio-rpi volumio[1234]: info: CURURI: music-library May 31 21:24:35 volumio-rpi volumio[1234]: info: Preload queue cleared May 31 21:24:37 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 21:24:37 volumio-rpi volumio[1234]: info: CURURI: music-library/USB May 31 21:24:37 volumio-rpi volumio[1234]: info: Preload queue cleared May 31 21:24:38 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 21:24:38 volumio-rpi volumio[1234]: info: CURURI: music-library/USB/EAGET忆捷 May 31 21:24:38 volumio-rpi volumio[1234]: info: Preload queue cleared May 31 21:24:38 volumio-rpi volumio[1234]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 31 21:24:38 volumio-rpi volumio[1234]: info: Retrying Login Due to Network Error May 31 21:24:42 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 21:24:42 volumio-rpi volumio[1234]: info: CURURI: music-library/USB/EAGET忆捷/4.口水歌 May 31 21:24:42 volumio-rpi volumio[1234]: info: Preload queue cleared May 31 21:24:42 volumio-rpi volumio[1234]: info: Preloading song: music-library/USB/EAGET忆捷/4.口水歌/中島美嘉+-+僕が死のうと思ったのは+(曾经我也想过一了百了).flac May 31 21:24:42 volumio-rpi volumio[1234]: info: Exploding uri music-library/USB/EAGET忆捷/4.口水歌/中島美嘉+-+僕が死のうと思ったのは+(曾经我也想过一了百了).flac in service mpd May 31 21:24:42 volumio-rpi volumio[1234]: info: ALBUMART /albumart?cacheid=963&web=%E4%B8%AD%E5%B3%B6%E7%BE%8E%E5%98%89/DEARS/extralarge&path=%2Fmnt%2FUSB%2FEAGET%E5%BF%86%E6%8D%B7%2F4.%E5%8F%A3%E6%B0%B4%E6%AD%8C%2F%E4%B8%AD%E5%B3%B6%E7%BE%8E%E5%98%89%2B-%2B%E5%83%95%E3%81%8C%E6%AD%BB%E3%81%AE%E3%81%86%E3%81%A8%E6%80%9D%E3%81%A3%E3%81%9F%E3%81%AE%E3%81%AF%2B(%E6%9B%BE%E7%BB%8F%E6%88%91%E4%B9%9F%E6%83%B3%E8%BF%87%E4%B8%80%E4%BA%86%E7%99%BE%E4%BA%86).flac&metadata=false May 31 21:24:42 volumio-rpi volumio[1234]: info: URI /mnt/USB/EAGET忆捷/4.口水歌/中島美嘉+-+僕が死のうと思ったのは+(曾经我也想过一了百了).flac May 31 21:24:48 volumio-rpi volumio[1234]: info: MyVolumio login type: Token May 31 21:24:50 volumio-rpi volumio[1234]: (node:1234) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit May 31 21:24:51 volumio-rpi volumio[1234]: info: Preload queue cleared May 31 21:24:51 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 21:24:51 volumio-rpi volumio[1234]: info: CoreStateMachine::ClearQueue May 31 21:24:51 volumio-rpi volumio[1234]: info: CoreStateMachine::stop May 31 21:24:51 volumio-rpi volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 21:24:51 volumio-rpi volumio[1234]: info: CoreStateMachine::stPlaybackTimer May 31 21:24:51 volumio-rpi volumio[1234]: info: CoreStateMachine::updateTrackBlock May 31 21:24:51 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrackBlock May 31 21:24:51 volumio-rpi volumio[1234]: info: CoreStateMachine::pushState May 31 21:24:51 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 1 May 31 21:24:51 volumio-rpi volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 21:24:51 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioPushState May 31 21:24:51 volumio-rpi volumio[1234]: info: CoreStateMachine::serviceStop May 31 21:24:51 volumio-rpi volumio[1234]: info: CorePlayQueue::getTrack 1 May 31 21:24:51 volumio-rpi volumio[1234]: info: CoreCommandRouter::serviceStop May 31 21:24:51 volumio-rpi volumio[1234]: info: ControllerMpd::stop May 31 21:24:51 volumio-rpi volumio[1234]: verbose: ControllerMpd::sendMpdCommand stop May 31 21:24:51 volumio-rpi volumio[1234]: info: CorePlayQueue::clearPlayQueue May 31 21:24:51 volumio-rpi volumio[1234]: info: CorePlayQueue::saveQueue May 31 21:24:51 volumio-rpi volumio[1234]: info: CoreCommandRouter::volumioPushQueue May 31 21:24:51 volumio-rpi volumio[1234]: info: CoreStateMachine::addQueueItems May 31 21:24:51 volumio-rpi volumio[1234]: info: CorePlayQueue::addQueueItems May 31 21:24:51 volumio-rpi volumio[1234]: info: Preload queue cleared May 31 21:24:51 volumio-rpi volumio[1234]: info: Adding Item to queue: music-library/USB/EAGET忆捷/4.口水歌/HIFI年代5《青海青》限量开盘母带ORMCD柏菲[低速整轨WAV] May 31 21:24:51 volumio-rpi volumio[1234]: info: Exploding uri music-library/USB/EAGET忆捷/4.口水歌/HIFI年代5《青海青》限量开盘母带ORMCD柏菲[低速整轨WAV] in service mpd May 31 21:24:51 volumio-rpi volumio[1234]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false May 31 21:24:51 volumio-rpi volumio[1234]: info: peppy_screensaver: Stop with metadata — grace timer 5000ms May 31 21:24:51 volumio-rpi volumio[1234]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 31 21:24:51 volumio-rpi volumio[1234]: info: touch_display: Setting screensaver timeout to 30 seconds. May 31 21:24:51 volumio-rpi volumio[1234]: info: sendMpdCommand stop took 634 milliseconds May 31 21:24:51 volumio-rpi volumio[1234]: info: May 31 21:24:51 volumio-rpi volumio[1234]: ---------------------------- MPD announces state update: player May 31 21:24:51 volumio-rpi volumio[1234]: info: ControllerMpd::getState May 31 21:24:51 volumio-rpi volumio[1234]: verbose: ControllerMpd::sendMpdCommand status May 31 21:24:51 volumio-rpi volumio[1234]: info: May 31 21:24:51 volumio-rpi volumio[1234]: ---------------------------- MPD announces state update: player May 31 21:24:51 volumio-rpi volumio[1234]: info: ControllerMpd::getState May 31 21:24:51 volumio-rpi volumio[1234]: verbose: ControllerMpd::sendMpdCommand status May 31 21:24:51 volumio-rpi volumio[1234]: info: May 31 21:24:51 volumio-rpi volumio[1234]: ---------------------------- MPD announces state update: player May 31 21:24:51 volumio-rpi volumio[1234]: info: ALBUMART /albumart?cacheid=963&web=/%E9%9D%92%E6%B5%B7%E9%9D%92/extralarge&path=%2Fmnt%2FUSB%2FEAGET%E5%BF%86%E6%8D%B7%2F4.%E5%8F%A3%E6%B0%B4%E6%AD%8C%2FHIFI%E5%B9%B4%E4%BB%A35%E3%80%8A%E9%9D%92%E6%B5%B7%E9%9D%92%E3%80%8B%E9%99%90%E9%87%8F%E5%BC%80%E7%9B%98%E6%AF%8D%E5%B8%A6ORMCD%E6%9F%8F%E8%8F%B2%5B%E4%BD%8E%E9%80%9F%E6%95%B4%E8%BD%A8WAV%5D%2F%E6%9F%8F%E8%8F%B2%20-%20%E9%9D%92%E6%B5%B7%E9%9D%92.cue&metadata=false May 31 21:24:51 volumio-rpi volumio[1234]: info: URI /mnt/USB/EAGET忆捷/4.口水歌/HIFI年代5《青海青》限量开盘母带ORMCD柏菲[低速整轨WAV]/柏菲 - 青海青.cue May 31 21:24:51 volumio-rpi volumio[1234]: info: ALBUMART /albumart?cacheid=963&web=/%E9%9D%92%E6%B5%B7%E9%9D%92/extralarge&path=%2Fmnt%2FUSB%2FEAGET%E5%BF%86%E6%8D%B7%2F4.%E5%8F%A3%E6%B0%B4%E6%AD%8C%2FHIFI%E5%B9%B4%E4%BB%A35%E3%80%8A%E9%9D%92%E6%B5%B7%E9%9D%92%E3%80%8B%E9%99%90%E9%87%8F%E5%BC%80%E7%9B%98%E6%AF%8D%E5%B8%A6ORMCD%E6%9F%8F%E8%8F%B2%5B%E4%BD%8E%E9%80%9F%E6%95%B4%E8%BD%A8WAV%5D%2F%E6%9F%8F%E8%8F%B2%20-%20%E9%9D%92%E6%B5%B7%E9%9D%92.cue&metadata=false May 31 21:24:51 volumio-rpi volumio[1234]: info: URI /mnt/USB/EAGET忆捷/4.口水歌/HIFI年代5《青海青》限量开盘母带ORMCD柏菲[低速整轨WAV]/柏菲 - 青海青.cue May 31 21:24:51 volumio-rpi volumio[1234]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 21:24:51 volumio-rpi volumio[1234]: Error: Unable to resolve or reject the same promise twice May 31 21:24:51 volumio-rpi volumio[1234]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 31 21:24:51 volumio-rpi volumio[1234]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 May 31 21:24:51 volumio-rpi volumio[1234]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) May 31 21:24:51 volumio-rpi volumio[1234]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) May 31 21:24:51 volumio-rpi volumio[1234]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) May 31 21:24:51 volumio-rpi volumio[1234]: at Socket.emit (node:events:514:28) May 31 21:24:51 volumio-rpi volumio[1234]: at addChunk (node:internal/streams/readable:343:12) May 31 21:24:51 volumio-rpi volumio[1234]: at readableAddChunk (node:internal/streams/readable:312:11) May 31 21:24:51 volumio-rpi volumio[1234]: at Readable.push (node:internal/streams/readable:253:10) May 31 21:24:51 volumio-rpi volumio[1234]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) May 31 21:24:51 volumio-rpi volumio[1234]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 21:24:53 volumio-rpi sudo[3426]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-31 21:23' May 31 21:24:53 volumio-rpi sudo[3426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"