-- Logs begin at Thu 2019-02-14 19:11:59 JST, end at Thu 2025-05-29 06:20:33 JST. --
May 29 06:19:06 volumio ntpd[708]: Soliciting pool server 129.250.35.251
May 29 06:19:07 volumio ntpd[708]: Soliciting pool server 103.131.151.30
May 29 06:19:07 volumio volumio[910]: info: Starting Streaming Service Transparent Proxy
May 29 06:19:07 volumio volumio[910]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
May 29 06:19:07 volumio volumio[910]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
May 29 06:19:07 volumio volumio[910]: info: Streaming services startup
May 29 06:19:07 volumio volumio[910]: info: Starting Streaming Daemon
May 29 06:19:07 volumio sudo[1372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
May 29 06:19:07 volumio sudo[1372]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 29 06:19:07 volumio volumio[910]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
May 29 06:19:07 volumio sudo[1372]: pam_unix(sudo:session): session closed for user root
May 29 06:19:07 volumio volumio[910]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory
May 29 06:19:07 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 29 06:19:07 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 29 06:19:07 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 29 06:19:08 volumio ntpd[708]: Soliciting pool server 122.215.240.51
May 29 06:19:08 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 29 06:19:08 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 29 06:19:08 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 29 06:19:08 volumio volumio[910]: info: CoreCommandRouter::volumioGetState
May 29 06:19:08 volumio volumio[910]: info: CorePlayQueue::getTrack 0
May 29 06:19:08 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 29 06:19:08 volumio volumio[910]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
May 29 06:19:08 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
May 29 06:19:08 volumio volumio[910]: info: Received Get System Version
May 29 06:19:08 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 29 06:19:08 volumio volumio[910]: info: Received Get System Info
May 29 06:19:08 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 29 06:19:08 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 29 06:19:08 volumio volumio[910]: info: Discovery: Getting this device information
May 29 06:19:08 volumio volumio[910]: info: CoreCommandRouter::volumioGetState
May 29 06:19:08 volumio volumio[910]: info: CorePlayQueue::getTrack 0
May 29 06:19:08 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 29 06:19:08 volumio volumio[910]: info: CoreCommandRouter::volumioPlay
May 29 06:19:08 volumio volumio[910]: info: CoreStateMachine::play index undefined
May 29 06:19:08 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined
May 29 06:19:08 volumio volumio[910]: info: CorePlayQueue::getTrack 0
May 29 06:19:08 volumio volumio[910]: info: CoreStateMachine::startPlaybackTimer
May 29 06:19:08 volumio volumio[910]: info: CorePlayQueue::getTrack 0
May 29 06:19:08 volumio volumio[910]: verbose: ControllerMpd::clearAddPlayTracks USB/4Gb/B-Series/B-020 Paul Mauriat-2.mp3
May 29 06:19:08 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand stop
May 29 06:19:08 volumio volumio[910]: error: Cannot start Volumio Streaming Daemon
May 29 06:19:08 volumio volumio[910]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
May 29 06:19:08 volumio volumio[910]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
May 29 06:19:08 volumio volumio[910]: info: sendMpdCommand stop took 58 milliseconds
May 29 06:19:08 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand clear
May 29 06:19:08 volumio volumio[910]: info:
May 29 06:19:08 volumio volumio[910]: ---------------------------- MPD announces system playlist update
May 29 06:19:08 volumio volumio[910]: info: Ignoring MPD Status Update
May 29 06:19:08 volumio volumio[910]: info: sendMpdCommand clear took 18 milliseconds
May 29 06:19:08 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand add "USB/4Gb/B-Series/B-020 Paul Mauriat-2.mp3"
May 29 06:19:08 volumio volumio[910]: info:
May 29 06:19:08 volumio ntpd[708]: Soliciting pool server 167.179.119.205
May 29 06:19:10 volumio volumio[910]: ---------------------------- MPD announces system playlist update
May 29 06:19:10 volumio volumio[910]: info: Ignoring MPD Status Update
May 29 06:19:10 volumio volumio[910]: info:
May 29 06:19:10 volumio volumio[910]: ---------------------------- MPD announces system playlist update
May 29 06:19:10 volumio volumio[910]: info: Ignoring MPD Status Update
May 29 06:19:10 volumio volumio[910]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2
May 29 06:19:10 volumio volumio[910]: error: updateQueue error: null
May 29 06:19:10 volumio volumio[910]: info:
May 29 06:19:10 volumio volumio[910]: ---------------------------- MPD announces system playlist update
May 29 06:19:10 volumio volumio[910]: info: Ignoring MPD Status Update
May 29 06:19:10 volumio volumio[910]: info: ------------------------------ 75ms
May 29 06:19:10 volumio volumio[910]: info: sendMpdCommand add "USB/4Gb/B-Series/B-020 Paul Mauriat-2.mp3" took 67 milliseconds
May 29 06:19:10 volumio volumio[910]: info: ------------------------------ 61ms
May 29 06:19:10 volumio volumio[910]: info: ------------------------------ 59ms
May 29 06:19:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand play
May 29 06:19:10 volumio volumio[910]: info:
May 29 06:19:10 volumio volumio[910]: ---------------------------- MPD announces system playlist update
May 29 06:19:10 volumio volumio[910]: info: Ignoring MPD Status Update
May 29 06:19:10 volumio volumio[910]: info:
May 29 06:19:10 volumio volumio[910]: ---------------------------- MPD announces system playlist update
May 29 06:19:10 volumio volumio[910]: info: Ignoring MPD Status Update
May 29 06:19:10 volumio volumio[910]: info: ------------------------------ 41ms
May 29 06:19:10 volumio volumio[910]: info: sendMpdCommand play took 35 milliseconds
May 29 06:19:10 volumio volumio[910]: info: ------------------------------ 34ms
May 29 06:19:10 volumio volumio[910]: info: ------------------------------ 31ms
May 29 06:19:10 volumio volumio[910]: verbose: New Socket.io Connection to 192.168.0.9 from 192.168.0.5 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 3
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::volumioGetState
May 29 06:19:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::volumioGetVisibleSources
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::volumioGetState
May 29 06:19:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::volumioGetState
May 29 06:19:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
May 29 06:19:10 volumio volumio[910]: info: Received Get System Info
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 29 06:19:09 volumio ntpd[708]: Soliciting pool server 172.237.12.44
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 29 06:19:10 volumio volumio[910]: info: Discovery: Getting this device information
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::volumioGetState
May 29 06:19:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::volumioGetState
May 29 06:19:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0
May 29 06:19:10 volumio volumio[910]: info: Listing playlists
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue
May 29 06:19:10 volumio volumio[910]: info: CoreStateMachine::getQueue
May 29 06:19:10 volumio volumio[910]: info: CorePlayQueue::getQueue
May 29 06:19:09 volumio ntpd[708]: Soliciting pool server 50.7.159.155
May 29 06:19:10 volumio ntpd[708]: Soliciting pool server 202.181.103.212
May 29 06:19:10 volumio ntpd[708]: Soliciting pool server 158.51.111.136
May 29 06:19:10 volumio volumio[910]: info:
May 29 06:19:10 volumio volumio[910]: ---------------------------- MPD announces state update: player
May 29 06:19:10 volumio volumio[910]: info: ControllerMpd::getState
May 29 06:19:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status
May 29 06:19:10 volumio volumio[910]: info:
May 29 06:19:10 volumio volumio[910]: ---------------------------- MPD announces state update: player
May 29 06:19:10 volumio volumio[910]: info: ControllerMpd::getState
May 29 06:19:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status
May 29 06:19:10 volumio volumio[910]: info:
May 29 06:19:10 volumio volumio[910]: ---------------------------- MPD announces state update: player
May 29 06:19:10 volumio volumio[910]: info: ControllerMpd::getState
May 29 06:19:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status
May 29 06:19:10 volumio volumio[910]: info:
May 29 06:19:10 volumio volumio[910]: ---------------------------- MPD announces state update: player
May 29 06:19:10 volumio volumio[910]: info: ControllerMpd::getState
May 29 06:19:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status
May 29 06:19:10 volumio volumio[910]: info: sendMpdCommand status took 21 milliseconds
May 29 06:19:10 volumio volumio[910]: info: sendMpdCommand status took 20 milliseconds
May 29 06:19:10 volumio volumio[910]: info: sendMpdCommand status took 18 milliseconds
May 29 06:19:10 volumio volumio[910]: verbose: ControllerMpd::parseState
May 29 06:19:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 29 06:19:10 volumio volumio[910]: verbose: ControllerMpd::parseState
May 29 06:19:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 29 06:19:10 volumio volumio[910]: verbose: ControllerMpd::parseState
May 29 06:19:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 29 06:19:10 volumio volumio[910]: info:
May 29 06:19:10 volumio volumio[910]: ---------------------------- MPD announces state update: player
May 29 06:19:10 volumio volumio[910]: info: ControllerMpd::getState
May 29 06:19:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status
May 29 06:19:10 volumio volumio[910]: info:
May 29 06:19:10 volumio volumio[910]: ---------------------------- MPD announces state update: player
May 29 06:19:10 volumio volumio[910]: info: ControllerMpd::getState
May 29 06:19:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status
May 29 06:19:10 volumio volumio[910]: info: sendMpdCommand status took 89 milliseconds
May 29 06:19:10 volumio volumio[910]: info: sendMpdCommand playlistinfo took 83 milliseconds
May 29 06:19:10 volumio volumio[910]: info: sendMpdCommand playlistinfo took 82 milliseconds
May 29 06:19:10 volumio volumio[910]: info: sendMpdCommand playlistinfo took 82 milliseconds
May 29 06:19:10 volumio volumio[910]: info: sendMpdCommand status took 73 milliseconds
May 29 06:19:10 volumio volumio[910]: info: sendMpdCommand status took 71 milliseconds
May 29 06:19:10 volumio volumio[910]: verbose: ControllerMpd::parseState
May 29 06:19:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 29 06:19:10 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo
May 29 06:19:10 volumio volumio[910]: verbose: ControllerMpd::parseState
May 29 06:19:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 29 06:19:10 volumio volumio[910]: verbose: ControllerMpd::parseState
May 29 06:19:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 29 06:19:10 volumio volumio[910]: info: ControllerMpd::pushState
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::servicePushState
May 29 06:19:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0
May 29 06:19:10 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":2631,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"B-020 Paul Mauriat-2.mp3","artist":null,"album":null,"uri":"USB/4Gb/B-Series/B-020 Paul Mauriat-2.mp3","trackType":"mp3"}
May 29 06:19:10 volumio volumio[910]: verbose: CURRENT POSITION 0
May 29 06:19:10 volumio volumio[910]: info: CoreStateMachine::syncState stateService play
May 29 06:19:10 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop
May 29 06:19:10 volumio volumio[910]: info: ControllerMpd::pushState
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::servicePushState
May 29 06:19:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0
May 29 06:19:10 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":2631,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"B-020 Paul Mauriat-2.mp3","artist":null,"album":null,"uri":"USB/4Gb/B-Series/B-020 Paul Mauriat-2.mp3","trackType":"mp3"}
May 29 06:19:10 volumio volumio[910]: verbose: CURRENT POSITION 0
May 29 06:19:10 volumio volumio[910]: info: CoreStateMachine::syncState stateService play
May 29 06:19:10 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play
May 29 06:19:10 volumio volumio[910]: info: Received an update from plugin. extracting info from payload
May 29 06:19:10 volumio volumio[910]: info: CoreStateMachine::pushState
May 29 06:19:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushState
May 29 06:19:10 volumio volumio[910]: info: CoreStateMachine::pushState
May 29 06:19:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushState
May 29 06:19:10 volumio volumio[910]: info: ControllerMpd::pushState
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::servicePushState
May 29 06:19:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0
May 29 06:19:10 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":2631,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"B-020 Paul Mauriat-2.mp3","artist":null,"album":null,"uri":"USB/4Gb/B-Series/B-020 Paul Mauriat-2.mp3","trackType":"mp3"}
May 29 06:19:10 volumio volumio[910]: verbose: CURRENT POSITION 0
May 29 06:19:10 volumio volumio[910]: info: CoreStateMachine::syncState stateService play
May 29 06:19:10 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play
May 29 06:19:10 volumio volumio[910]: info: Received an update from plugin. extracting info from payload
May 29 06:19:10 volumio volumio[910]: info: CoreStateMachine::pushState
May 29 06:19:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushState
May 29 06:19:10 volumio volumio[910]: info: CoreStateMachine::pushState
May 29 06:19:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushState
May 29 06:19:10 volumio volumio[910]: info: ------------------------------ 153ms
May 29 06:19:10 volumio volumio[910]: info: ------------------------------ 167ms
May 29 06:19:10 volumio volumio[910]: info: ------------------------------ 165ms
May 29 06:19:10 volumio ntpd[708]: Soliciting pool server 23.81.40.154
May 29 06:19:10 volumio ntpd[708]: Soliciting pool server 160.16.113.133
May 29 06:19:10 volumio ntpd[708]: Soliciting pool server 129.250.35.250
May 29 06:19:10 volumio volumio[910]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
May 29 06:19:10 volumio volumio[910]: info: sendMpdCommand playlistinfo took 91 milliseconds
May 29 06:19:10 volumio volumio[910]: info: sendMpdCommand playlistinfo took 87 milliseconds
May 29 06:19:10 volumio volumio[910]: info: sendMpdCommand playlistinfo took 86 milliseconds
May 29 06:19:10 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo
May 29 06:19:10 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo
May 29 06:19:10 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo
May 29 06:19:10 volumio volumio[910]: info: ControllerMpd::pushState
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::servicePushState
May 29 06:19:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0
May 29 06:19:10 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":2631,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"B-020 Paul Mauriat-2.mp3","artist":null,"album":null,"uri":"USB/4Gb/B-Series/B-020 Paul Mauriat-2.mp3","trackType":"mp3"}
May 29 06:19:10 volumio volumio[910]: verbose: CURRENT POSITION 0
May 29 06:19:10 volumio volumio[910]: info: CoreStateMachine::syncState stateService play
May 29 06:19:10 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play
May 29 06:19:10 volumio volumio[910]: info: Received an update from plugin. extracting info from payload
May 29 06:19:10 volumio volumio[910]: info: CoreStateMachine::pushState
May 29 06:19:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushState
May 29 06:19:10 volumio volumio[910]: info: CoreStateMachine::pushState
May 29 06:19:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushState
May 29 06:19:10 volumio volumio[910]: info: ControllerMpd::pushState
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::servicePushState
May 29 06:19:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0
May 29 06:19:10 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":2631,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"B-020 Paul Mauriat-2.mp3","artist":null,"album":null,"uri":"USB/4Gb/B-Series/B-020 Paul Mauriat-2.mp3","trackType":"mp3"}
May 29 06:19:10 volumio volumio[910]: verbose: CURRENT POSITION 0
May 29 06:19:10 volumio volumio[910]: info: CoreStateMachine::syncState stateService play
May 29 06:19:10 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play
May 29 06:19:10 volumio volumio[910]: info: Received an update from plugin. extracting info from payload
May 29 06:19:10 volumio volumio[910]: info: CoreStateMachine::pushState
May 29 06:19:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushState
May 29 06:19:10 volumio volumio[910]: info: CoreStateMachine::pushState
May 29 06:19:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushState
May 29 06:19:10 volumio volumio[910]: info: ControllerMpd::pushState
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::servicePushState
May 29 06:19:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0
May 29 06:19:10 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":2631,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"B-020 Paul Mauriat-2.mp3","artist":null,"album":null,"uri":"USB/4Gb/B-Series/B-020 Paul Mauriat-2.mp3","trackType":"mp3"}
May 29 06:19:10 volumio volumio[910]: verbose: CURRENT POSITION 0
May 29 06:19:10 volumio volumio[910]: info: CoreStateMachine::syncState stateService play
May 29 06:19:10 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play
May 29 06:19:10 volumio volumio[910]: info: Received an update from plugin. extracting info from payload
May 29 06:19:10 volumio volumio[910]: info: CoreStateMachine::pushState
May 29 06:19:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushState
May 29 06:19:10 volumio volumio[910]: info: CoreStateMachine::pushState
May 29 06:19:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushState
May 29 06:19:10 volumio volumio[910]: info: ------------------------------ 267ms
May 29 06:19:10 volumio volumio[910]: info: ------------------------------ 248ms
May 29 06:19:10 volumio volumio[910]: info: ------------------------------ 252ms
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
May 29 06:19:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
May 29 06:19:10 volumio volumio-remote-updater[496]: No test mode
May 29 06:19:10 volumio volumio-remote-updater[496]: No alpha test mode
May 29 06:19:10 volumio volumio[910]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
May 29 06:19:11 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 29 06:19:11 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 29 06:19:11 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
May 29 06:19:11 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
May 29 06:19:11 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
May 29 06:19:11 volumio volumio[910]: info: CoreCommandRouter::volumioGetBrowseSources
May 29 06:19:11 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 29 06:19:11 volumio ntpd[708]: Soliciting pool server 64.176.59.83
May 29 06:19:11 volumio ntpd[708]: Soliciting pool server 138.3.216.120
May 29 06:19:11 volumio ntpd[708]: Soliciting pool server 133.130.121.141
May 29 06:19:11 volumio volumio[910]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Various Fixes for QOBUZ Connect
\n
\nNEW ADDITIONS
\n\n- Qobuz Connect Ready
\n
\n","title":"Update v3.812","updateavailable":true}
May 29 06:19:12 volumio ntpd[708]: Soliciting pool server 162.159.200.123
May 29 06:19:12 volumio ntpd[708]: Soliciting pool server 45.76.211.39
May 29 06:19:12 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
May 29 06:19:16 volumio ntpd[708]: receive: Unexpected origin timestamp 0xebe1fc53.6e1f950a does not match aorg 0000000000.00000000 from server@160.16.113.133 xmt 0xebe1fc54.0f85a12f
May 29 06:19:16 volumio ntpd[708]: receive: Unexpected origin timestamp 0xebe1fc53.6e2380b5 does not match aorg 0000000000.00000000 from server@122.215.240.51 xmt 0xebe1fc54.0ecaccf5
May 29 06:19:16 volumio ntpd[708]: receive: Unexpected origin timestamp 0xebe1fc53.6e22312d does not match aorg 0000000000.00000000 from server@167.179.119.205 xmt 0xebe1fc54.0f94a4bc
May 29 06:19:16 volumio ntpd[708]: receive: Unexpected origin timestamp 0xebe1fc53.6e20ea11 does not match aorg 0000000000.00000000 from server@158.51.111.136 xmt 0xebe1fc54.103a1758
May 29 06:19:16 volumio ntpd[708]: receive: Unexpected origin timestamp 0xebe1fc53.6e266595 does not match aorg 0000000000.00000000 from server@129.250.35.251 xmt 0xebe1fc54.0fff6f89
May 29 06:19:16 volumio ntpd[708]: receive: Unexpected origin timestamp 0xebe1fc53.6e1e09bf does not match aorg 0000000000.00000000 from server@23.81.40.154 xmt 0xebe1fc54.102c860a
May 29 06:19:16 volumio ntpd[708]: receive: Unexpected origin timestamp 0xebe1fc53.6e1a7400 does not match aorg 0000000000.00000000 from server@64.176.59.83 xmt 0xebe1fc54.1014bf70
May 29 06:19:18 volumio volumio[910]: Upnp client error: Error: This socket has been ended by the other party
May 29 06:19:19 volumio volumio[910]: info: MyVolumio token set successfully
May 29 06:19:19 volumio volumio[910]: info: MYVOLUMIO: Adding device
May 29 06:19:19 volumio volumio[910]: info: MYVOLUMIO: Evaluating Server
May 29 06:19:19 volumio systemd[1]: Stopping Music Player Daemon...
May 29 06:19:19 volumio systemd[1]: mpd.service: Succeeded.
May 29 06:19:19 volumio systemd[1]: Stopped Music Player Daemon.
May 29 06:19:19 volumio systemd[1]: Starting Music Player Daemon...
May 29 06:19:19 volumio volumio[910]: STREAMING PROXY: Starting server on port 3245
May 29 06:19:19 volumio volumio[910]: Node JS runtime: 14
May 29 06:19:19 volumio sudo[1418]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
May 29 06:19:19 volumio sudo[1418]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 29 06:19:19 volumio sudo[1418]: pam_unix(sudo:session): session closed for user root
May 29 06:19:20 volumio volumio[910]: info: MyVolumio status changed
May 29 06:19:20 volumio volumio[910]: info: Streaming services startup
May 29 06:19:20 volumio volumio[910]: info: Starting Streaming Daemon
May 29 06:19:20 volumio volumio[910]: info: Removing browser output: myVolumio user plan is not superstar
May 29 06:19:20 volumio volumio[910]: info: Removing audio output:
May 29 06:19:20 volumio volumio[910]: info: Stoppping Tunnel 1
May 29 06:19:20 volumio sudo[1437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
May 29 06:19:20 volumio sudo[1437]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 29 06:19:20 volumio sudo[1437]: pam_unix(sudo:session): session closed for user root
May 29 06:19:20 volumio volumio[910]: error: Cannot start Volumio Streaming Daemon
May 29 06:19:20 volumio volumio[910]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
May 29 06:19:20 volumio volumio[910]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
May 29 06:19:20 volumio sudo[1440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
May 29 06:19:20 volumio sudo[1440]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 29 06:19:20 volumio sudo[1440]: pam_unix(sudo:session): session closed for user root
May 29 06:19:20 volumio volumio[910]: info: Remote SSH Stopped
May 29 06:19:20 volumio volumio[910]: info: Setting Geolocation for MyVolumio to as1
May 29 06:19:20 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 29 06:19:20 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 29 06:19:20 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 29 06:19:20 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 29 06:19:20 volumio volumio[910]: error: Upnp client error: Error: This socket has been ended by the other party
May 29 06:19:20 volumio volumio[910]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
May 29 06:19:21 volumio volumio[910]: info: Updating MyVolumio device info
May 29 06:19:21 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 29 06:19:21 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 29 06:19:21 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 29 06:19:21 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 29 06:19:21 volumio mpd[1429]: May 29 06:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
May 29 06:19:21 volumio systemd[1]: Started Music Player Daemon.
May 29 06:19:21 volumio mpd_monitor.sh[469]: MPD restarted due to no mpc output.
May 29 06:19:21 volumio volumio[910]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
May 29 06:19:24 volumio volumio[910]: info: MYVOLUMIO: Adding device
May 29 06:19:24 volumio volumio[910]: info: MYVOLUMIO: Evaluating Server
May 29 06:19:25 volumio volumio[910]: info: Setting Geolocation for MyVolumio to as1
May 29 06:19:25 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 29 06:19:26 volumio volumio[910]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
May 29 06:19:26 volumio volumio[910]: info: Updating MyVolumio device info
May 29 06:19:26 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 29 06:19:27 volumio volumio[910]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
May 29 06:19:28 volumio volumio[910]: error: MyVolumio Plugin failed to authenticate in a timely fashion
May 29 06:19:28 volumio volumio[910]: info: Completed starting MyVolumio Plugin
May 29 06:19:28 volumio volumio[910]: [Metrics] CommandRouter: 61s 446.31ms
May 29 06:19:28 volumio volumio[910]: info: CoreCommandRouter::volumiosetStartupVolume
May 29 06:19:28 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 29 06:19:28 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 29 06:19:28 volumio volumio[910]: info: CoreCommandRouter::Close All Modals sent
May 29 06:19:28 volumio volumio[910]: info: CoreCommandRouter::Close All Modals sent
May 29 06:19:29 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
May 29 06:19:29 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
May 29 06:19:29 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
May 29 06:19:35 volumio volumio[910]: info: BOOT COMPLETED
May 29 06:19:46 volumio volumio[1275]: ........................................................................................................................................................................................................................................................................................................++++
May 29 06:19:46 volumio volumio[1275]: e is 65537 (0x010001)
May 29 06:19:46 volumio volumio[1275]: writing RSA key
May 29 06:19:50 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin
May 29 06:19:50 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin
May 29 06:19:50 volumio volumio[910]: info: Retrieving Cloud Streaming UI
May 29 06:19:50 volumio volumio[910]: info: Getting Tidal Cloud Configuration
May 29 06:19:50 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin
May 29 06:19:50 volumio volumio[910]: info: Getting Qobuz Cloud Configuration
May 29 06:19:50 volumio volumio[910]: info: Asking plugin for UI Config
May 29 06:19:50 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin
May 29 06:19:50 volumio volumio[910]: info: Getting Spotify Cloud Configuration
May 29 06:19:50 volumio volumio[910]: info: Asking plugin for UI Config
May 29 06:19:50 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin
May 29 06:19:50 volumio volumio[910]: info: Saving Spotify Acccount
May 29 06:19:50 volumio volumio[910]: info: Got Tidal Cloud Configuration
May 29 06:19:50 volumio volumio[910]: info: Got it
May 29 06:19:50 volumio volumio[910]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
May 29 06:19:50 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin
May 29 06:19:50 volumio volumio[910]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
May 29 06:19:50 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
May 29 06:19:50 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
May 29 06:19:50 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 29 06:19:50 volumio volumio[910]: info: CoreCommandRouter::volumioGetBrowseSources
May 29 06:19:50 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 29 06:19:50 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:19:50 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:19:50 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:19:50 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:19:50 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:19:50 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:19:50 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
May 29 06:19:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb
May 29 06:19:53 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand rescan
May 29 06:19:53 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:19:53 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:19:53 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:19:53 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:19:53 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:19:53 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:19:54 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 29 06:19:54 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:19:54 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:19:54 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:19:54 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:19:54 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:19:54 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:19:58 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 29 06:19:58 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:19:58 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:19:58 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:19:58 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:19:58 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:19:58 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:00 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb
May 29 06:20:00 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand rescan
May 29 06:20:00 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:00 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:00 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:00 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:00 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:00 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:02 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 29 06:20:02 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:02 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:02 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:02 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:02 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:02 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:05 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb
May 29 06:20:05 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand update
May 29 06:20:05 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:05 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:05 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:05 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:05 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:05 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:06 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 29 06:20:06 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:06 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:06 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:06 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:06 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:06 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:10 volumio kernel: usb 1-1.2: USB disconnect, device number 4
May 29 06:20:10 volumio volumio[910]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/c8b2223e-01 /dev/disk/by-label/4Gb /dev/disk/by-id/usb-Kingmax_USB2.0_FlashDisk_001D7D4F2EDBSK8909140271-0:0-part1 /dev/disk/by-uuid/DC54C44F54C42DD8 /dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part1","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"26","ID_BUS":"usb","ID_FS_LABEL":"4Gb","ID_FS_LABEL_ENC":"4Gb","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"DC54C44F54C42DD8","ID_FS_UUID_ENC":"DC54C44F54C42DD8","ID_INSTANCE":"0:0","ID_MODEL":"USB2.0_FlashDisk","ID_MODEL_ENC":"USB2.0\\x20FlashDisk","ID_MODEL_ID":"6211","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"8192","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"7729152","ID_PART_ENTRY_TYPE":"0x7","ID_PART_ENTRY_UUID":"c8b2223e-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"c8b2223e","ID_PATH":"platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"1.00","ID_SERIAL":"Kingmax_USB2.0_FlashDisk_001D7D4F2EDBSK8909140271-0:0","ID_SERIAL_SHORT":"001D7D4F2EDBSK8909140271","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Kingmax","ID_VENDOR_ENC":"Kingmax\\x20","ID_VENDOR_ID":"1687","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"1979","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"5929334"}
May 29 06:20:10 volumio systemd[1]: media-4Gb.mount: Succeeded.
May 29 06:20:10 volumio sudo[1699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1
May 29 06:20:10 volumio sudo[1699]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 29 06:20:10 volumio ntfs-3g[1099]: Unmounting /dev/sda1 (4Gb)
May 29 06:20:10 volumio sudo[1699]: pam_unix(sudo:session): session closed for user root
May 29 06:20:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 29 06:20:10 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:10 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:10 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:10 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:10 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:10 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:12 volumio kernel: usb 1-1.3: new high-speed USB device number 5 using dwc_otg
May 29 06:20:12 volumio kernel: usb 1-1.3: New USB device found, idVendor=1687, idProduct=6211, bcdDevice= 2.00
May 29 06:20:12 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
May 29 06:20:12 volumio kernel: usb 1-1.3: Product: USB2.0 FlashDisk
May 29 06:20:12 volumio kernel: usb 1-1.3: Manufacturer: Kingmax
May 29 06:20:12 volumio kernel: usb 1-1.3: SerialNumber: 001D7D4F2EDBSK8909140271
May 29 06:20:12 volumio kernel: usb-storage 1-1.3:1.0: USB Mass Storage device detected
May 29 06:20:12 volumio kernel: scsi host0: usb-storage 1-1.3:1.0
May 29 06:20:13 volumio kernel: scsi 0:0:0:0: Direct-Access Kingmax USB2.0 FlashDisk 1.00 PQ: 0 ANSI: 2
May 29 06:20:14 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0
May 29 06:20:14 volumio kernel: sd 0:0:0:0: [sda] 7737344 512-byte logical blocks: (3.96 GB/3.69 GiB)
May 29 06:20:14 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off
May 29 06:20:14 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 23 00 00 00
May 29 06:20:14 volumio kernel: sd 0:0:0:0: [sda] No Caching mode page found
May 29 06:20:14 volumio kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through
May 29 06:20:14 volumio kernel: sda: sda1
May 29 06:20:14 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk
May 29 06:20:14 volumio volumio[910]: info: Mounting Device 4Gb
May 29 06:20:14 volumio sudo[1721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/4Gb -o noatime,dmask=0000,fmask=0000,iocharset=utf8
May 29 06:20:14 volumio sudo[1721]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 29 06:20:14 volumio ntfs-3g[1724]: Version 2017.3.23AR.3 integrated FUSE 28
May 29 06:20:14 volumio sudo[1721]: pam_unix(sudo:session): session closed for user root
May 29 06:20:14 volumio ntfs-3g[1724]: Mounted /dev/sda1 (Read-Write, label "4Gb", NTFS 3.1)
May 29 06:20:14 volumio ntfs-3g[1724]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8
May 29 06:20:14 volumio ntfs-3g[1724]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096
May 29 06:20:14 volumio ntfs-3g[1724]: Global ownership and permissions enforced, configuration type 7
May 29 06:20:14 volumio volumio[910]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/4Gb
May 29 06:20:14 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 29 06:20:14 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:14 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:14 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:14 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:14 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:14 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:15 volumio volumio[910]: info: Scanning removed location : "USB/4Gb"
May 29 06:20:18 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 29 06:20:18 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:18 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:18 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:18 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:18 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:18 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:22 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 29 06:20:22 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:22 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:22 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:22 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:22 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:22 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:23 volumio volumio[910]: info: Disabling MyMusic plugin upnp
May 29 06:20:23 volumio sudo[1762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service
May 29 06:20:23 volumio sudo[1762]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 29 06:20:23 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD...
May 29 06:20:23 volumio volumio[910]: error: Upnp client error: Error: This socket has been ended by the other party
May 29 06:20:27 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 29 06:20:27 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:27 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:27 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:27 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:27 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:27 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 29 06:20:31 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:31 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:31 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:31 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:31 volumio volumio[910]: error: MPD error: Error: This socket has been ended by the other party
May 29 06:20:31 volumio volumio[910]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 29 06:20:32 volumio volumio[910]: info: Enabling MyMusic plugin upnp
May 29 06:20:32 volumio volumio[910]: info: Enabling plugin upnp
May 29 06:20:32 volumio volumio[910]: info: Loading plugin "upnp"...
May 29 06:20:32 volumio volumio[910]: info: [1748467232670] Starting Upmpd Daemon
May 29 06:20:32 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 29 06:20:32 volumio volumio[910]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 29 06:20:32 volumio volumio[910]: Error: listen EADDRINUSE: address already in use :::6599
May 29 06:20:32 volumio volumio[910]: at Server.setupListenHandle [as _listen2] (net.js:1318:16)
May 29 06:20:32 volumio volumio[910]: at listenInCluster (net.js:1366:12)
May 29 06:20:32 volumio volumio[910]: at Server.listen (net.js:1452:7)
May 29 06:20:32 volumio volumio[910]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17)
May 29 06:20:32 volumio volumio[910]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38)
May 29 06:20:32 volumio volumio[910]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19)
May 29 06:20:32 volumio volumio[910]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)
May 29 06:20:32 volumio volumio[910]: at processTicksAndRejections (internal/process/task_queues.js:75:11) {
May 29 06:20:32 volumio volumio[910]: code: 'EADDRINUSE',
May 29 06:20:32 volumio volumio[910]: errno: -98,
May 29 06:20:32 volumio volumio[910]: syscall: 'listen',
May 29 06:20:32 volumio volumio[910]: address: '::',
May 29 06:20:32 volumio volumio[910]: port: 6599
May 29 06:20:32 volumio volumio[910]: }
May 29 06:20:32 volumio volumio[910]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 29 06:20:33 volumio sudo[1808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-29 06:19
May 29 06:20:33 volumio sudo[1808]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET"
VOLUMIO_VERSION="3.785"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"