-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Mon 2024-07-22 15:39:37 UTC. --
Jul 22 15:38:11 volumio ntpd[646]: receive: Unexpected origin timestamp 0xea43f949.8d07424a does not match aorg 0000000000.00000000 from server@212.45.144.3 xmt 0xea48fb63.8dfca129
Jul 22 15:38:11 volumio systemd[1]: Starting Daily apt download activities...
Jul 22 15:38:11 volumio volumio[901]: ------------------------------------ BT MESSAGE: BT STATUS: running
Jul 22 15:38:11 volumio volumio[901]: BT PLUGIN MESSAGE: Playing: false
Jul 22 15:38:11 volumio volumio[901]: BT PLUGIN MESSAGE: VOLATILE:
Jul 22 15:38:11 volumio volumio[901]: BT PLUGIN MESSAGE: STATE:undefined
Jul 22 15:38:12 volumio systemd[1]: apt-daily.service: Succeeded.
Jul 22 15:38:12 volumio systemd[1]: Started Daily apt download activities.
Jul 22 15:38:12 volumio systemd[1]: Starting Daily apt upgrade and clean activities...
Jul 22 15:38:12 volumio volumio[901]: ------------------------------------ BT MESSAGE: BT STATUS: running
Jul 22 15:38:12 volumio volumio[901]: BT PLUGIN MESSAGE: Playing: false
Jul 22 15:38:12 volumio volumio[901]: BT PLUGIN MESSAGE: VOLATILE:
Jul 22 15:38:12 volumio volumio[901]: BT PLUGIN MESSAGE: STATE:undefined
Jul 22 15:38:12 volumio systemd[1]: apt-daily-upgrade.service: Succeeded.
Jul 22 15:38:12 volumio systemd[1]: Started Daily apt upgrade and clean activities.
Jul 22 15:38:12 volumio volumio[901]: info: mDNS: A device disapperared from network
Jul 22 15:38:12 volumio volumio[901]: info: mDNS: Device volumio disapperared from network
Jul 22 15:38:13 volumio volumio[901]: info: Discovery: adding abf1716b-cd61-4e8f-b0a5-6f8338b007dc
Jul 22 15:38:13 volumio volumio[901]: info: mDNS: Found device Volumio
Jul 22 15:38:13 volumio volumio[901]: info: CoreCommandRouter::volumioGetState
Jul 22 15:38:13 volumio volumio[901]: info: CorePlayQueue::getTrack 0
Jul 22 15:38:13 volumio sudo[1405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jul 22 15:38:13 volumio sudo[1405]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 22 15:38:13 volumio sudo[1405]: pam_unix(sudo:session): session closed for user root
Jul 22 15:38:13 volumio sudo[1408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 22 15:38:13 volumio sudo[1408]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 22 15:38:13 volumio sudo[1408]: pam_unix(sudo:session): session closed for user root
Jul 22 15:38:13 volumio volumio[901]: verbose: New Socket.io Connection to 192.168.1.73 from 192.168.1.23 UA: okhttp/4.9.2 Total Clients: 5
Jul 22 15:38:13 volumio volumio[901]: info: CoreCommandRouter::volumioGetVisibleSources
Jul 22 15:38:13 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 22 15:38:13 volumio volumio[901]: info: CoreCommandRouter::volumioGetState
Jul 22 15:38:13 volumio volumio[901]: info: CorePlayQueue::getTrack 0
Jul 22 15:38:13 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jul 22 15:38:13 volumio volumio[901]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom
Jul 22 15:38:13 volumio volumio[901]: info: Listing playlists
Jul 22 15:38:13 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jul 22 15:38:13 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Jul 22 15:38:13 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Jul 22 15:38:13 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jul 22 15:38:15 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 22 15:38:15 volumio sudo[1292]: pam_unix(sudo:session): session closed for user root
Jul 22 15:38:15 volumio volumio[901]: info: Cannot mount NAS at system boot, trial number 4 ,stopping
Jul 22 15:38:15 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation.
Jul 22 15:38:15 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -2
Jul 22 15:38:16 volumio volumio[901]: info: CoreCommandRouter::volumioGetQueue
Jul 22 15:38:16 volumio volumio[901]: info: CoreStateMachine::getQueue
Jul 22 15:38:16 volumio volumio[901]: info: CorePlayQueue::getQueue
Jul 22 15:38:16 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 22 15:38:18 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri
Jul 22 15:38:18 volumio volumio[901]: info: handleBrowseUri took 142 milliseconds
Jul 22 15:38:22 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri
Jul 22 15:38:22 volumio volumio[901]: info: handleBrowseUri took 289 milliseconds
Jul 22 15:38:31 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jul 22 15:38:31 volumio volumio[901]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Jul 22 15:38:31 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Jul 22 15:38:31 volumio volumio[901]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio
Jul 22 15:38:31 volumio volumio[901]: info: Received Get System Version
Jul 22 15:38:31 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 22 15:38:55 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri
Jul 22 15:38:56 volumio volumio[901]: info: handleBrowseUri took 654 milliseconds
Jul 22 15:39:03 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri
Jul 22 15:39:03 volumio volumio[901]: info: handleBrowseUri took 320 milliseconds
Jul 22 15:39:04 volumio volumio[901]: info: Executing endpoint metavolumio
Jul 22 15:39:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jul 22 15:39:04 volumio volumio[901]: info: Executing endpoint metavolumio
Jul 22 15:39:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jul 22 15:39:05 volumio volumio[901]: info: CoreCommandRouter::volumioReplaceandPlayItems
Jul 22 15:39:05 volumio volumio[901]: info: CoreStateMachine::ClearQueue
Jul 22 15:39:05 volumio volumio[901]: info: CoreStateMachine::stop
Jul 22 15:39:05 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 22 15:39:05 volumio volumio[901]: verbose: UNSET VOLATILE
Jul 22 15:39:05 volumio volumio[901]: info: CorePlayQueue::clearPlayQueue
Jul 22 15:39:05 volumio volumio[901]: info: CorePlayQueue::saveQueue
Jul 22 15:39:05 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Jul 22 15:39:05 volumio volumio[901]: info: CoreCommandRouter::volumioPushQueue
Jul 22 15:39:05 volumio volumio[901]: info: CoreStateMachine::addQueueItems
Jul 22 15:39:05 volumio volumio[901]: info: CorePlayQueue::addQueueItems
Jul 22 15:39:05 volumio volumio[901]: info: Adding Item to queue: qobuz://album/xi8t9wgbu55na
Jul 22 15:39:05 volumio volumio[901]: info: Exploding uri qobuz://album/xi8t9wgbu55na in service qobuz
Jul 22 15:39:05 volumio volumio[901]: info: explodeUri took 157 milliseconds
Jul 22 15:39:05 volumio volumio[901]: info: CorePlayQueue::saveQueue
Jul 22 15:39:05 volumio volumio[901]: info: CoreCommandRouter::volumioPushQueue
Jul 22 15:39:05 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock
Jul 22 15:39:05 volumio volumio[901]: info: CorePlayQueue::getTrackBlock
Jul 22 15:39:05 volumio volumio[901]: info: CoreCommandRouter::volumioPlay
Jul 22 15:39:05 volumio volumio[901]: verbose: UNSET VOLATILE
Jul 22 15:39:05 volumio volumio[901]: info: CoreStateMachine::play index 0
Jul 22 15:39:05 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 22 15:39:05 volumio volumio[901]: info: CoreStateMachine::stop
Jul 22 15:39:05 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 22 15:39:05 volumio volumio[901]: verbose: UNSET VOLATILE
Jul 22 15:39:05 volumio volumio[901]: info: CoreStateMachine::play index undefined
Jul 22 15:39:05 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 22 15:39:05 volumio volumio[901]: info: CorePlayQueue::getTrack 0
Jul 22 15:39:05 volumio volumio[901]: info: CoreStateMachine::startPlaybackTimer
Jul 22 15:39:05 volumio volumio[901]: info: CorePlayQueue::getTrack 0
Jul 22 15:39:05 volumio volumio[901]: info: [1721662745465] ControllerQobuz::clearAddPlayTrack
Jul 22 15:39:05 volumio volumio[901]: info: getStreamUrl took 242 milliseconds
Jul 22 15:39:05 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand stop
Jul 22 15:39:05 volumio volumio[901]: info: sendMpdCommand stop took 2 milliseconds
Jul 22 15:39:05 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand clear
Jul 22 15:39:05 volumio volumio[901]: info:
Jul 22 15:39:05 volumio volumio[901]: ---------------------------- MPD announces system playlist update
Jul 22 15:39:05 volumio volumio[901]: info: Ignoring MPD Status Update
Jul 22 15:39:05 volumio volumio[901]: info: sendMpdCommand clear took 3 milliseconds
Jul 22 15:39:05 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand load "http://streaming-qobuz-std.akamaized.net:80/file?uid=1867909&eid=120515208&fmt=6&profile=raw&app_id=539451548&cid=1326084&etsp=1721666345&hmac=Ol5066jz0H27MemTobHc8ZOZk0A"
Jul 22 15:39:05 volumio volumio[901]: error: updateQueue error: null
Jul 22 15:39:05 volumio volumio[901]: info: ------------------------------ 6ms
Jul 22 15:39:06 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand add "http://streaming-qobuz-std.akamaized.net:80/file?uid=1867909&eid=120515208&fmt=6&profile=raw&app_id=539451548&cid=1326084&etsp=1721666345&hmac=Ol5066jz0H27MemTobHc8ZOZk0A"
Jul 22 15:39:06 volumio volumio[901]: info:
Jul 22 15:39:06 volumio volumio[901]: ---------------------------- MPD announces system playlist update
Jul 22 15:39:06 volumio volumio[901]: info: Ignoring MPD Status Update
Jul 22 15:39:06 volumio volumio[901]: info: sendMpdCommand add "http://streaming-qobuz-std.akamaized.net:80/file?uid=1867909&eid=120515208&fmt=6&profile=raw&app_id=539451548&cid=1326084&etsp=1721666345&hmac=Ol5066jz0H27MemTobHc8ZOZk0A" took 2 milliseconds
Jul 22 15:39:06 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService mpd
Jul 22 15:39:06 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand play
Jul 22 15:39:06 volumio volumio[901]: info: ------------------------------ 5ms
Jul 22 15:39:06 volumio volumio[901]: info: sendMpdCommand play took 3 milliseconds
Jul 22 15:39:06 volumio volumio[901]: info:
Jul 22 15:39:06 volumio volumio[901]: ---------------------------- MPD announces state update: player
Jul 22 15:39:06 volumio volumio[901]: info: ControllerMpd::getState
Jul 22 15:39:06 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status
Jul 22 15:39:06 volumio volumio[901]: info:
Jul 22 15:39:06 volumio volumio[901]: ---------------------------- MPD announces state update: player
Jul 22 15:39:06 volumio volumio[901]: info: sendMpdCommand status took 11 milliseconds
Jul 22 15:39:06 volumio volumio[901]: info: ControllerMpd::getState
Jul 22 15:39:06 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status
Jul 22 15:39:06 volumio volumio[901]: verbose: ControllerMpd::parseState
Jul 22 15:39:06 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 22 15:39:06 volumio volumio[901]: info: sendMpdCommand status took 3 milliseconds
Jul 22 15:39:06 volumio volumio[901]: info: sendMpdCommand playlistinfo took 2 milliseconds
Jul 22 15:39:06 volumio volumio[901]: verbose: ControllerMpd::parseState
Jul 22 15:39:06 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 22 15:39:06 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo
Jul 22 15:39:06 volumio volumio[901]: info: ControllerMpd::pushState
Jul 22 15:39:06 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Jul 22 15:39:06 volumio volumio[901]: info: CorePlayQueue::getTrack 0
Jul 22 15:39:06 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=1867909&eid=120515208&fmt=6&profile=raw&app_id=539451548&cid=1326084&etsp=1721666345&hmac=Ol5066jz0H27MemTobHc8ZOZk0A","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=1867909&eid=120515208&fmt=6&profile=raw&app_id=539451548&cid=1326084&etsp=1721666345&hmac=Ol5066jz0H27MemTobHc8ZOZk0A","trackType":""}
Jul 22 15:39:06 volumio volumio[901]: verbose: CURRENT POSITION 0
Jul 22 15:39:06 volumio volumio[901]: info: CoreStateMachine::syncState stateService play
Jul 22 15:39:06 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus stop
Jul 22 15:39:06 volumio volumio[901]: info: CoreStateMachine::pushState
Jul 22 15:39:06 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 22 15:39:06 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Jul 22 15:39:06 volumio volumio[901]: info: ------------------------------ 38ms
Jul 22 15:39:06 volumio volumio[901]: info: sendMpdCommand playlistinfo took 19 milliseconds
Jul 22 15:39:06 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo
Jul 22 15:39:06 volumio volumio[901]: info: ControllerMpd::pushState
Jul 22 15:39:06 volumio volumio[901]: info: CoreCommandRouter::servicePushState
Jul 22 15:39:06 volumio volumio[901]: info: CorePlayQueue::getTrack 0
Jul 22 15:39:06 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=1867909&eid=120515208&fmt=6&profile=raw&app_id=539451548&cid=1326084&etsp=1721666345&hmac=Ol5066jz0H27MemTobHc8ZOZk0A","artist":null,"album":null,"uri":"http://streaming-qobuz-std.akamaized.net:80/file?uid=1867909&eid=120515208&fmt=6&profile=raw&app_id=539451548&cid=1326084&etsp=1721666345&hmac=Ol5066jz0H27MemTobHc8ZOZk0A","trackType":""}
Jul 22 15:39:06 volumio volumio[901]: verbose: CURRENT POSITION 0
Jul 22 15:39:06 volumio volumio[901]: info: CoreStateMachine::syncState stateService play
Jul 22 15:39:06 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus play
Jul 22 15:39:06 volumio volumio[901]: info: Received an update from plugin. extracting info from payload
Jul 22 15:39:06 volumio volumio[901]: info: CoreStateMachine::pushState
Jul 22 15:39:06 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 22 15:39:06 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Jul 22 15:39:06 volumio volumio[901]: info: CoreStateMachine::pushState
Jul 22 15:39:06 volumio volumio[901]: info: CoreCommandRouter::volumioPushState
Jul 22 15:39:06 volumio volumio[901]: info: ------------------------------ 57ms
Jul 22 15:39:11 volumio volumio[901]: error: Failed request for metavolumio API
Jul 22 15:39:37 volumio volumio[901]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 22 15:39:37 volumio volumio[901]: Error: read ECONNRESET
Jul 22 15:39:37 volumio volumio[901]: at TCP.onStreamRead (internal/stream_base_commons.js:209:20) {
Jul 22 15:39:37 volumio volumio[901]: errno: -104,
Jul 22 15:39:37 volumio volumio[901]: code: 'ECONNRESET',
Jul 22 15:39:37 volumio volumio[901]: syscall: 'read'
Jul 22 15:39:37 volumio volumio[901]: }
Jul 22 15:39:37 volumio volumio[901]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 22 15:39:37 volumio sudo[1436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-22 15:38
Jul 22 15:39:37 volumio sudo[1436]: 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="543f2de901f774399af6eae9d76f06fe4162e5dd"
VOLUMIO_FE_VERSION="dff19baf69ee85af3381c73e932cb6852dfe38e0"
VOLUMIO_FE3_VERSION="081f1f7a36b167d793d831ee89ac02e7f8f3ee4b"
VOLUMIO_BE_VERSION="1eeee4d17c356c3967f07f80a7dfdbc12de2e066"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 08 Dec 2021 12:21:52 PM CET"
VOLUMIO_VERSION="3.173"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="1c9433a7294bad3fb7682386561b54d4"