-- 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"