-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Mon 2025-03-17 21:52:26 CST. --
Mar 17 21:51:00 volumio go-librespot[7757]: time="2025-03-17T21:51:00+08:00" level=debug msg="completed challenge"
Mar 17 21:51:00 volumio go-librespot[7757]: time="2025-03-17T21:51:00+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: TravelRestriction"
Mar 17 21:51:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 17 21:51:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 17 21:51:00 volumio volumio[7555]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up
Mar 17 21:51:00 volumio volumio[7555]: (node:7555) UnhandledPromiseRejectionWarning: Error: socket hang up
Mar 17 21:51:00 volumio volumio[7555]: at connResetException (internal/errors.js:607:14)
Mar 17 21:51:00 volumio volumio[7555]: at Socket.socketOnEnd (_http_client.js:493:23)
Mar 17 21:51:00 volumio volumio[7555]: at Socket.emit (events.js:327:22)
Mar 17 21:51:00 volumio volumio[7555]: at endReadableNT (internal/streams/readable.js:1327:12)
Mar 17 21:51:00 volumio volumio[7555]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Mar 17 21:51:00 volumio volumio[7555]: (Use `node --trace-warnings ...` to show where the warning was created)
Mar 17 21:51:00 volumio volumio[7555]: (node:7555) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1)
Mar 17 21:51:00 volumio volumio[7555]: (node:7555) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code.
Mar 17 21:51:00 volumio volumio[7555]: info: Connection to go-librespot Websocket closed
Mar 17 21:51:01 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 17 21:51:01 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 17 21:51:01 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Mar 17 21:51:03 volumio volumio[7555]: info: Initializing connection to go-librespot Websocket
Mar 17 21:51:03 volumio volumio[7555]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 17 21:51:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Mar 17 21:51:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1.
Mar 17 21:51:03 volumio systemd[1]: Stopped go-librespot Daemon.
Mar 17 21:51:03 volumio systemd[1]: Started go-librespot Daemon.
Mar 17 21:51:03 volumio go-librespot[7887]: Librespot-go daemon starting...
Mar 17 21:51:03 volumio go-librespot[7887]: time="2025-03-17T21:51:03+08:00" level=info msg="generated new device id: d4b776c9619cf1178395abfc96c9d05c5b3d8a2c"
Mar 17 21:51:03 volumio go-librespot[7887]: time="2025-03-17T21:51:03+08:00" level=debug msg="stored credentials not found"
Mar 17 21:51:06 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Mar 17 21:51:06 volumio volumio[7555]: info: CURURI: music-library/USB/288016118015E65A/无损U盘
Mar 17 21:51:06 volumio mpd[7703]: exception: No database
Mar 17 21:51:06 volumio volumio[7555]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database
Mar 17 21:51:06 volumio volumio[7555]: info: Preload queue cleared
Mar 17 21:51:06 volumio volumio[7555]: info: Initializing connection to go-librespot Websocket
Mar 17 21:51:06 volumio go-librespot[7887]: time="2025-03-17T21:51:06+08:00" level=debug msg="new websocket client"
Mar 17 21:51:06 volumio volumio[7555]: info: Connection to go-librespot Websocket established
Mar 17 21:51:09 volumio volumio[7555]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Alan%20Tam/2f4f9104-3bc5-46c4-a25e-91abe5a9486c.jpg'
Mar 17 21:51:09 volumio volumio[7555]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%88%80%E9%83%8E/2002%E5%B9%B4%E7%9A%84%E7%AC%AC%E4%B8%80%E5%9C%BA%E9%9B%AA/476d9611-be58-4fd4-814d-7b5bcb37556c.jpg'
Mar 17 21:51:09 volumio go-librespot[7887]: time="2025-03-17T21:51:09+08:00" level=debug msg="obtained new client token: AADAods/e7Gk475zF90jXmMOdhDlP6x3eXLUxkC1LOTYfmSOxnhXW5/vzwsMFbYnSClDYs3r0RmqQl8+Dk3HBNlMZ9t/0CpsbdcMOnkau6LCz7z5QfA9286fsg5Rfh/TrxkWxRYqji9UDwGHvqt/s7lbFXgZm586eSoVW68rayXZ/DVHjlcMWZfCyx9/wye6XYPHU/prsKdhm+kulGsNkFBUnm5y81SKQdEu0Or0fpvhcQHInZMuZqeO/JMXslVayQ=="
Mar 17 21:51:09 volumio volumio[7555]: info: Getting Spotify volume
Mar 17 21:51:09 volumio volumio[7555]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10
Mar 17 21:51:09 volumio volumio[7555]: info: CoreCommandRouter::volumioGetState
Mar 17 21:51:09 volumio volumio[7555]: info: CorePlayQueue::getTrack 0
Mar 17 21:51:09 volumio volumio[7555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Mar 17 21:51:09 volumio volumio[7555]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E6%83%A0%E5%A6%B9/Bad%20Boy/0d1601a6-173d-4c4c-8d6e-96106a559e45.png'
Mar 17 21:51:09 volumio volumio[7555]: info: CoreCommandRouter::volumioPlay
Mar 17 21:51:09 volumio volumio[7555]: info: CoreStateMachine::play index undefined
Mar 17 21:51:09 volumio volumio[7555]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 17 21:51:09 volumio volumio[7555]: info: CorePlayQueue::getTrack 0
Mar 17 21:51:09 volumio volumio[7555]: info: CoreStateMachine::startPlaybackTimer
Mar 17 21:51:09 volumio volumio[7555]: info: CorePlayQueue::getTrack 0
Mar 17 21:51:09 volumio volumio[7555]: verbose: ControllerMpd::clearAddPlayTracks USB/288016118015E65A/无损U盘/酷我下载/Achim Reichel - Aloha Heja He.flac
Mar 17 21:51:09 volumio volumio[7555]: verbose: ControllerMpd::sendMpdCommand stop
Mar 17 21:51:09 volumio volumio[7555]: info: sendMpdCommand stop took 1 milliseconds
Mar 17 21:51:09 volumio volumio[7555]: verbose: ControllerMpd::sendMpdCommand clear
Mar 17 21:51:09 volumio volumio[7555]: info:
Mar 17 21:51:09 volumio volumio[7555]: ---------------------------- MPD announces system playlist update
Mar 17 21:51:09 volumio volumio[7555]: info: Ignoring MPD Status Update
Mar 17 21:51:09 volumio volumio[7555]: info: sendMpdCommand clear took 3 milliseconds
Mar 17 21:51:09 volumio volumio[7555]: verbose: ControllerMpd::sendMpdCommand add "USB/288016118015E65A/无损U盘/酷我下载/Achim Reichel - Aloha Heja He.flac"
Mar 17 21:51:09 volumio mpd[7703]: exception: No database
Mar 17 21:51:09 volumio volumio[7555]: info:
Mar 17 21:51:09 volumio volumio[7555]: ---------------------------- MPD announces system playlist update
Mar 17 21:51:09 volumio volumio[7555]: info: Ignoring MPD Status Update
Mar 17 21:51:09 volumio volumio[7555]: info:
Mar 17 21:51:09 volumio volumio[7555]: ---------------------------- MPD announces system playlist update
Mar 17 21:51:09 volumio volumio[7555]: info: Ignoring MPD Status Update
Mar 17 21:51:09 volumio volumio[7555]: error: updateQueue error: null
Mar 17 21:51:09 volumio volumio[7555]: error: updateQueue error: null
Mar 17 21:51:09 volumio volumio[7555]: error: updateQueue error: null
Mar 17 21:51:09 volumio volumio[7555]: info: ------------------------------ 9ms
Mar 17 21:51:09 volumio volumio[7555]: info: ------------------------------ 6ms
Mar 17 21:51:09 volumio volumio[7555]: info: ------------------------------ 6ms
Mar 17 21:51:09 volumio volumio[7555]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/BEYOND/BEYOND%20IV/dcbe6734-e51f-4b0b-b9b4-2f24d7e4c744.jpg'
Mar 17 21:51:09 volumio volumio[7555]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/BEYOND/Beyond%20The%20Stage/cbcf24b6-72e0-45ec-aa26-95812e8c17af.jpg'
Mar 17 21:51:10 volumio volumio[7555]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%8D%89%E8%9C%A2/Grasshopper%20IV/225e8b44-e562-4251-9b6a-73d7003dc1b1.jpg'
Mar 17 21:51:10 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Mar 17 21:51:10 volumio volumio[7555]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10
Mar 17 21:51:10 volumio volumio[7555]: info: CoreCommandRouter::volumioGetState
Mar 17 21:51:10 volumio volumio[7555]: info: CorePlayQueue::getTrack 0
Mar 17 21:51:11 volumio volumio[7555]: info: CoreCommandRouter::volumioPlay
Mar 17 21:51:11 volumio volumio[7555]: info: CoreStateMachine::play index undefined
Mar 17 21:51:11 volumio volumio[7555]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 17 21:51:11 volumio volumio[7555]: info: CorePlayQueue::getTrack 0
Mar 17 21:51:11 volumio volumio[7555]: info: CoreStateMachine::startPlaybackTimer
Mar 17 21:51:11 volumio volumio[7555]: info: CorePlayQueue::getTrack 0
Mar 17 21:51:11 volumio volumio[7555]: verbose: ControllerMpd::clearAddPlayTracks USB/288016118015E65A/无损U盘/酷我下载/Achim Reichel - Aloha Heja He.flac
Mar 17 21:51:11 volumio volumio[7555]: verbose: ControllerMpd::sendMpdCommand stop
Mar 17 21:51:11 volumio volumio[7555]: info: sendMpdCommand stop took 2 milliseconds
Mar 17 21:51:11 volumio volumio[7555]: verbose: ControllerMpd::sendMpdCommand clear
Mar 17 21:51:11 volumio volumio[7555]: info:
Mar 17 21:51:11 volumio volumio[7555]: ---------------------------- MPD announces system playlist update
Mar 17 21:51:11 volumio volumio[7555]: info: Ignoring MPD Status Update
Mar 17 21:51:11 volumio volumio[7555]: info: sendMpdCommand clear took 1 milliseconds
Mar 17 21:51:11 volumio volumio[7555]: verbose: ControllerMpd::sendMpdCommand add "USB/288016118015E65A/无损U盘/酷我下载/Achim Reichel - Aloha Heja He.flac"
Mar 17 21:51:11 volumio mpd[7703]: exception: No database
Mar 17 21:51:11 volumio volumio[7555]: info:
Mar 17 21:51:11 volumio volumio[7555]: ---------------------------- MPD announces system playlist update
Mar 17 21:51:11 volumio volumio[7555]: info: Ignoring MPD Status Update
Mar 17 21:51:11 volumio volumio[7555]: info:
Mar 17 21:51:11 volumio volumio[7555]: ---------------------------- MPD announces system playlist update
Mar 17 21:51:11 volumio volumio[7555]: info: Ignoring MPD Status Update
Mar 17 21:51:11 volumio volumio[7555]: error: updateQueue error: null
Mar 17 21:51:11 volumio volumio[7555]: error: updateQueue error: null
Mar 17 21:51:11 volumio volumio[7555]: error: updateQueue error: null
Mar 17 21:51:11 volumio volumio[7555]: info: ------------------------------ 4ms
Mar 17 21:51:11 volumio volumio[7555]: info: ------------------------------ 4ms
Mar 17 21:51:11 volumio volumio[7555]: info: ------------------------------ 3ms
Mar 17 21:51:15 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 17 21:51:15 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 17 21:51:15 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Mar 17 21:51:15 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Mar 17 21:51:15 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Mar 17 21:51:15 volumio volumio[7555]: info: CoreCommandRouter::volumioGetBrowseSources
Mar 17 21:51:15 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 17 21:51:15 volumio go-librespot[7887]: time="2025-03-17T21:51:15+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Mar 17 21:51:20 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Mar 17 21:51:20 volumio volumio[7555]: info: CURURI: music-library/USB/288016118015E65A
Mar 17 21:51:20 volumio mpd[7703]: exception: No database
Mar 17 21:51:20 volumio volumio[7555]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database
Mar 17 21:51:20 volumio volumio[7555]: info: Preload queue cleared
Mar 17 21:51:26 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Mar 17 21:51:26 volumio volumio[7555]: info: CURURI: music-library
Mar 17 21:51:26 volumio mpd[7703]: exception: No database
Mar 17 21:51:26 volumio volumio[7555]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database
Mar 17 21:51:26 volumio volumio[7555]: info: Preload queue cleared
Mar 17 21:51:27 volumio volumio-remote-updater[549]: No test mode
Mar 17 21:51:27 volumio volumio-remote-updater[549]: No alpha test mode
Mar 17 21:51:27 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Mar 17 21:51:28 volumio volumio[7555]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Improved translations
NEW ADDITIONS
- Add DSD-quirk for several USB DACs
- Enable install-to-NVME on CM5 and newer RPi5 revisions
- Fix potential issues with QOBUZ and TIDAL Streaming on slow connections
","title":"Update v3.795","updateavailable":true}
Mar 17 21:51:28 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Mar 17 21:51:29 volumio go-librespot[7887]: time="2025-03-17T21:51:29+08:00" level=debug msg="completed keyexchange"
Mar 17 21:51:30 volumio go-librespot[7887]: time="2025-03-17T21:51:30+08:00" level=debug msg="completed challenge"
Mar 17 21:51:30 volumio go-librespot[7887]: time="2025-03-17T21:51:30+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: TravelRestriction"
Mar 17 21:51:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 17 21:51:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 17 21:51:30 volumio volumio[7555]: (node:7555) UnhandledPromiseRejectionWarning: Error: socket hang up
Mar 17 21:51:30 volumio volumio[7555]: at connResetException (internal/errors.js:607:14)
Mar 17 21:51:30 volumio volumio[7555]: at Socket.socketOnEnd (_http_client.js:493:23)
Mar 17 21:51:30 volumio volumio[7555]: at Socket.emit (events.js:327:22)
Mar 17 21:51:30 volumio volumio[7555]: at endReadableNT (internal/streams/readable.js:1327:12)
Mar 17 21:51:30 volumio volumio[7555]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Mar 17 21:51:30 volumio volumio[7555]: (node:7555) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 2)
Mar 17 21:51:30 volumio volumio[7555]: info: Connection to go-librespot Websocket closed
Mar 17 21:51:31 volumio volumio[7555]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 17 21:51:31 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Mar 17 21:51:31 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Mar 17 21:51:31 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Mar 17 21:51:31 volumio volumio[7555]: info: Received Get System Version
Mar 17 21:51:31 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 17 21:51:33 volumio volumio[7555]: info: Initializing connection to go-librespot Websocket
Mar 17 21:51:33 volumio volumio[7555]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 17 21:51:33 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Mar 17 21:51:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2.
Mar 17 21:51:33 volumio systemd[1]: Stopped go-librespot Daemon.
Mar 17 21:51:33 volumio systemd[1]: Started go-librespot Daemon.
Mar 17 21:51:33 volumio go-librespot[7912]: Librespot-go daemon starting...
Mar 17 21:51:33 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard
Mar 17 21:51:33 volumio go-librespot[7912]: time="2025-03-17T21:51:33+08:00" level=info msg="generated new device id: 3de69adc4d4d087a576ff2648d4c8b1753a0f9a9"
Mar 17 21:51:33 volumio go-librespot[7912]: time="2025-03-17T21:51:33+08:00" level=debug msg="stored credentials not found"
Mar 17 21:51:33 volumio volumio[7555]: info: CoreCommandRouter::Reload Ui
Mar 17 21:51:34 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard
Mar 17 21:51:34 volumio volumio[7555]: info: CoreCommandRouter::Reload Ui
Mar 17 21:51:34 volumio volumio[7555]: info: CoreCommandRouter::volumioGetState
Mar 17 21:51:34 volumio volumio[7555]: info: CorePlayQueue::getTrack 0
Mar 17 21:51:36 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard
Mar 17 21:51:36 volumio volumio[7555]: info: CoreCommandRouter::Reload Ui
Mar 17 21:51:36 volumio volumio[7555]: info: CoreCommandRouter::volumioGetState
Mar 17 21:51:36 volumio volumio[7555]: info: CorePlayQueue::getTrack 0
Mar 17 21:51:36 volumio volumio[7555]: info: Initializing connection to go-librespot Websocket
Mar 17 21:51:36 volumio go-librespot[7912]: time="2025-03-17T21:51:36+08:00" level=debug msg="new websocket client"
Mar 17 21:51:36 volumio volumio[7555]: info: Connection to go-librespot Websocket established
Mar 17 21:51:36 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard
Mar 17 21:51:36 volumio volumio[7555]: info: CoreCommandRouter::Reload Ui
Mar 17 21:51:36 volumio volumio[7555]: info: CoreCommandRouter::volumioGetState
Mar 17 21:51:36 volumio volumio[7555]: info: CorePlayQueue::getTrack 0
Mar 17 21:51:38 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard
Mar 17 21:51:38 volumio volumio[7555]: info: CoreCommandRouter::Reload Ui
Mar 17 21:51:38 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard
Mar 17 21:51:38 volumio volumio[7555]: info: CoreCommandRouter::Reload Ui
Mar 17 21:51:38 volumio volumio[7555]: info: CoreCommandRouter::volumioGetState
Mar 17 21:51:38 volumio volumio[7555]: info: CorePlayQueue::getTrack 0
Mar 17 21:51:38 volumio volumio[7555]: info: CoreCommandRouter::volumioGetState
Mar 17 21:51:38 volumio volumio[7555]: info: CorePlayQueue::getTrack 0
Mar 17 21:51:39 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard
Mar 17 21:51:39 volumio volumio[7555]: info: CoreCommandRouter::Reload Ui
Mar 17 21:51:39 volumio volumio[7555]: info: CoreCommandRouter::volumioGetState
Mar 17 21:51:39 volumio volumio[7555]: info: CorePlayQueue::getTrack 0
Mar 17 21:51:39 volumio volumio[7555]: info: Getting Spotify volume
Mar 17 21:51:39 volumio volumio[7555]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9
Mar 17 21:51:39 volumio volumio[7555]: info: CoreCommandRouter::volumioGetState
Mar 17 21:51:39 volumio volumio[7555]: info: CorePlayQueue::getTrack 0
Mar 17 21:51:39 volumio volumio[7555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Mar 17 21:51:39 volumio go-librespot[7912]: time="2025-03-17T21:51:39+08:00" level=debug msg="obtained new client token: AAAQgNhDwXKAAthw7vljTORd6VcVGLsSmakcSxq6R1WQHEEx+DlL/sUNPLcRf0LrTqfy1r60Y+BcW3IDCc2Lavhv4iZjouYqxb21oY20eP/Pc1J5GXvOXWTCCRjJrIiS/ds+NY9LfIi3V6QjFHCpMeFkXVNC3ueBuokXYyfOapKME0IQQQCDNC96OEtDUBBroMMsLXRiReovyES6GN7Zhd/2/fLHakx1bfGPUv+n/acK/maNRn1X7HUpAMK3T75/DQ=="
Mar 17 21:51:44 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard
Mar 17 21:51:44 volumio volumio[7555]: info: CoreCommandRouter::Reload Ui
Mar 17 21:51:44 volumio volumio[7555]: info: CoreCommandRouter::volumioGetState
Mar 17 21:51:44 volumio volumio[7555]: info: CorePlayQueue::getTrack 0
Mar 17 21:51:45 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard
Mar 17 21:51:45 volumio volumio[7555]: info: CoreCommandRouter::Reload Ui
Mar 17 21:51:45 volumio volumio[7555]: info: CoreCommandRouter::volumioGetState
Mar 17 21:51:45 volumio volumio[7555]: info: CorePlayQueue::getTrack 0
Mar 17 21:51:46 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard
Mar 17 21:51:46 volumio volumio[7555]: info: CoreCommandRouter::Reload Ui
Mar 17 21:51:46 volumio volumio[7555]: info: CoreCommandRouter::volumioGetState
Mar 17 21:51:46 volumio volumio[7555]: info: CorePlayQueue::getTrack 0
Mar 17 21:51:54 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard
Mar 17 21:51:54 volumio volumio[7555]: info: CoreCommandRouter::Reload Ui
Mar 17 21:51:54 volumio volumio[7555]: info: CoreCommandRouter::volumioGetState
Mar 17 21:51:54 volumio volumio[7555]: info: CorePlayQueue::getTrack 0
Mar 17 21:51:54 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard
Mar 17 21:51:54 volumio volumio[7555]: info: CoreCommandRouter::Reload Ui
Mar 17 21:51:55 volumio volumio[7555]: info: CoreCommandRouter::volumioGetState
Mar 17 21:51:55 volumio volumio[7555]: info: CorePlayQueue::getTrack 0
Mar 17 21:51:56 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard
Mar 17 21:51:56 volumio volumio[7555]: info: CoreCommandRouter::Reload Ui
Mar 17 21:51:56 volumio volumio[7555]: info: CoreCommandRouter::volumioGetState
Mar 17 21:51:56 volumio volumio[7555]: info: CorePlayQueue::getTrack 0
Mar 17 21:52:03 volumio go-librespot[7912]: time="2025-03-17T21:52:03+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Mar 17 21:52:04 volumio volumio[7555]: info: CoreCommandRouter::volumioGetState
Mar 17 21:52:04 volumio volumio[7555]: info: CorePlayQueue::getTrack 0
Mar 17 21:52:04 volumio volumio[7555]: info: CoreCommandRouter::volumioGetState
Mar 17 21:52:04 volumio volumio[7555]: info: CorePlayQueue::getTrack 0
Mar 17 21:52:04 volumio sudo[7994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Mar 17 21:52:04 volumio sudo[7994]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 17 21:52:04 volumio sudo[7994]: pam_unix(sudo:session): session closed for user root
Mar 17 21:52:04 volumio sudo[7997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Mar 17 21:52:04 volumio sudo[7997]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 17 21:52:04 volumio sudo[7997]: pam_unix(sudo:session): session closed for user root
Mar 17 21:52:04 volumio volumio[7555]: verbose: New Socket.io Connection to 192.168.89.192 from 192.168.89.237 UA: Mozilla/5.0 (Linux; Android 13; SM-S9010 Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/111.0.5563.116 Mobile Safari/537.36 Total Clients: 8
Mar 17 21:52:05 volumio volumio[7555]: info: CoreCommandRouter::volumioGetVisibleSources
Mar 17 21:52:05 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 17 21:52:05 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Mar 17 21:52:05 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Mar 17 21:52:05 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Mar 17 21:52:05 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
Mar 17 21:52:05 volumio volumio[7555]: info: Received Get System Info
Mar 17 21:52:05 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 17 21:52:05 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 17 21:52:05 volumio volumio[7555]: info: Discovery: Getting this device information
Mar 17 21:52:05 volumio volumio[7555]: info: CoreCommandRouter::volumioGetState
Mar 17 21:52:05 volumio volumio[7555]: info: CorePlayQueue::getTrack 0
Mar 17 21:52:05 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 17 21:52:05 volumio volumio-remote-updater[549]: No test mode
Mar 17 21:52:05 volumio volumio-remote-updater[549]: No alpha test mode
Mar 17 21:52:05 volumio volumio[7555]: info: CoreCommandRouter::volumioGetState
Mar 17 21:52:05 volumio volumio[7555]: info: CorePlayQueue::getTrack 0
Mar 17 21:52:05 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Mar 17 21:52:05 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Mar 17 21:52:06 volumio volumio[7555]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Improved translations
NEW ADDITIONS
- Add DSD-quirk for several USB DACs
- Enable install-to-NVME on CM5 and newer RPi5 revisions
- Fix potential issues with QOBUZ and TIDAL Streaming on slow connections
","title":"Update v3.795","updateavailable":true}
Mar 17 21:52:06 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Mar 17 21:52:06 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Mar 17 21:52:08 volumio go-librespot[7912]: time="2025-03-17T21:52:08+08:00" level=debug msg="completed keyexchange"
Mar 17 21:52:08 volumio go-librespot[7912]: time="2025-03-17T21:52:08+08:00" level=debug msg="completed challenge"
Mar 17 21:52:09 volumio go-librespot[7912]: time="2025-03-17T21:52:09+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: TravelRestriction"
Mar 17 21:52:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 17 21:52:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 17 21:52:09 volumio volumio[7555]: (node:7555) UnhandledPromiseRejectionWarning: Error: socket hang up
Mar 17 21:52:09 volumio volumio[7555]: at connResetException (internal/errors.js:607:14)
Mar 17 21:52:09 volumio volumio[7555]: at Socket.socketOnEnd (_http_client.js:493:23)
Mar 17 21:52:09 volumio volumio[7555]: at Socket.emit (events.js:327:22)
Mar 17 21:52:09 volumio volumio[7555]: at endReadableNT (internal/streams/readable.js:1327:12)
Mar 17 21:52:09 volumio volumio[7555]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Mar 17 21:52:09 volumio volumio[7555]: (node:7555) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 3)
Mar 17 21:52:09 volumio volumio[7555]: info: Connection to go-librespot Websocket closed
Mar 17 21:52:09 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Mar 17 21:52:09 volumio volumio[7555]: info: Loading i18n strings for locale zh
Mar 17 21:52:09 volumio volumio[7555]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found.
Mar 17 21:52:09 volumio volumio[7555]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found.
Mar 17 21:52:09 volumio volumio[7555]: info: [now-playing] Volumio language changed - pushing localization settings
Mar 17 21:52:09 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Mar 17 21:52:09 volumio volumio[7555]: info: Setting timezone to Asia/Shanghai
Mar 17 21:52:09 volumio sudo[8004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Mar 17 21:52:09 volumio sudo[8004]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 17 21:52:09 volumio sudo[8004]: pam_unix(sudo:session): session closed for user root
Mar 17 21:52:09 volumio sudo[8010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Shanghai /etc/localtime
Mar 17 21:52:09 volumio sudo[8010]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 17 21:52:09 volumio sudo[8010]: pam_unix(sudo:session): session closed for user root
Mar 17 21:52:09 volumio sudo[8013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Mar 17 21:52:09 volumio sudo[8013]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 17 21:52:09 volumio sudo[8013]: pam_unix(sudo:session): session closed for user root
Mar 17 21:52:09 volumio sudo[8016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Shanghai
Mar 17 21:52:09 volumio sudo[8016]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 17 21:52:09 volumio dbus-daemon[563]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.45' (uid=0 pid=8017 comm="/usr/bin/timedatectl set-timezone Asia/Shanghai ")
Mar 17 21:52:09 volumio systemd[1]: Starting Time & Date Service...
Mar 17 21:52:09 volumio dbus-daemon[563]: [system] Successfully activated service 'org.freedesktop.timedate1'
Mar 17 21:52:09 volumio systemd[1]: Started Time & Date Service.
Mar 17 21:52:09 volumio sudo[8016]: pam_unix(sudo:session): session closed for user root
Mar 17 21:52:09 volumio volumio[7555]: Updating browse sources language
Mar 17 21:52:09 volumio volumio[7555]: Cannot find translation for source 80s80s Radio
Mar 17 21:52:09 volumio volumio[7555]: Cannot find translation for source Podcast
Mar 17 21:52:09 volumio volumio[7555]: Cannot find translation for source Spotify
Mar 17 21:52:09 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 17 21:52:09 volumio volumio[7555]: Cannot find translation for source 80s80s Radio
Mar 17 21:52:09 volumio volumio[7555]: Cannot find translation for source Podcast
Mar 17 21:52:09 volumio volumio[7555]: Cannot find translation for source Spotify
Mar 17 21:52:09 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Mar 17 21:52:09 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Mar 17 21:52:09 volumio sudo[8020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Mar 17 21:52:09 volumio sudo[8020]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 17 21:52:09 volumio volumio[7555]: info: Fetching Streaming Services browse cache
Mar 17 21:52:11 volumio sudo[8020]: pam_unix(sudo:session): session closed for user root
Mar 17 21:52:12 volumio volumio[7555]: info: Initializing connection to go-librespot Websocket
Mar 17 21:52:12 volumio volumio[7555]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 17 21:52:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Mar 17 21:52:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3.
Mar 17 21:52:12 volumio systemd[1]: Stopped go-librespot Daemon.
Mar 17 21:52:12 volumio systemd[1]: Started go-librespot Daemon.
Mar 17 21:52:12 volumio go-librespot[8022]: Librespot-go daemon starting...
Mar 17 21:52:12 volumio go-librespot[8022]: time="2025-03-17T21:52:12+08:00" level=info msg="generated new device id: 72700fc8984b8a29391e37b6c16579021cbd9c39"
Mar 17 21:52:12 volumio go-librespot[8022]: time="2025-03-17T21:52:12+08:00" level=debug msg="stored credentials not found"
Mar 17 21:52:15 volumio volumio[7555]: info: Initializing connection to go-librespot Websocket
Mar 17 21:52:15 volumio go-librespot[8022]: time="2025-03-17T21:52:15+08:00" level=debug msg="new websocket client"
Mar 17 21:52:15 volumio volumio[7555]: info: Connection to go-librespot Websocket established
Mar 17 21:52:18 volumio volumio[7555]: info: Getting Spotify volume
Mar 17 21:52:18 volumio volumio[7555]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9
Mar 17 21:52:18 volumio volumio[7555]: info: CoreCommandRouter::volumioGetState
Mar 17 21:52:18 volumio volumio[7555]: info: CorePlayQueue::getTrack 0
Mar 17 21:52:18 volumio volumio[7555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Mar 17 21:52:19 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings
Mar 17 21:52:19 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices
Mar 17 21:52:19 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Mar 17 21:52:19 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 17 21:52:19 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Mar 17 21:52:21 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions
Mar 17 21:52:21 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Mar 17 21:52:21 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber
Mar 17 21:52:21 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 17 21:52:21 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName
Mar 17 21:52:21 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 17 21:52:21 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Mar 17 21:52:21 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer
Mar 17 21:52:21 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 17 21:52:21 volumio volumio[7555]: info: Found match in i2s Card Database: setting mixer Digital for card Audiophonics I-Sabre ES9028Q2M
Mar 17 21:52:21 volumio volumio[7555]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Mar 17 21:52:21 volumio volumio[7555]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1
Mar 17 21:52:21 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume
Mar 17 21:52:21 volumio volumio[7555]: info: Disabling external Volume Control
Mar 17 21:52:21 volumio volumio[7555]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 17 21:52:21 volumio volumio[7555]: info: Enable softmixer device for audio device undefined
Mar 17 21:52:21 volumio volumio[7555]: info: Software Volume ALSA configuration written
Mar 17 21:52:21 volumio volumio[7555]: info: Preparing to generate the ALSA configuration file
Mar 17 21:52:21 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121
Mar 17 21:52:21 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121
Mar 17 21:52:21 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121
Mar 17 21:52:21 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Mar 17 21:52:21 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 17 21:52:21 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Mar 17 21:52:21 volumio volumio[7555]: info: Getting Alsa Cards List without I2S DAC
Mar 17 21:52:21 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber
Mar 17 21:52:21 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 17 21:52:22 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121
Mar 17 21:52:22 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121
Mar 17 21:52:22 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121
Mar 17 21:52:22 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Mar 17 21:52:22 volumio volumio[7555]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf
Mar 17 21:52:22 volumio volumio[7555]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf
Mar 17 21:52:22 volumio volumio[7555]: info: Reading ALSA contributions from plugins.
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 17 21:52:22 volumio volumio[7555]: info: Retrieving Cloud Streaming UI
Mar 17 21:52:22 volumio volumio[7555]: info: Getting Tidal Cloud Configuration
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 17 21:52:22 volumio volumio[7555]: info: Getting Qobuz Cloud Configuration
Mar 17 21:52:22 volumio volumio[7555]: info: Asking plugin for UI Config
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 17 21:52:22 volumio volumio[7555]: info: Getting Spotify Cloud Configuration
Mar 17 21:52:22 volumio volumio[7555]: info: Asking plugin for UI Config
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 17 21:52:22 volumio volumio[7555]: info: Saving Spotify Acccount
Mar 17 21:52:22 volumio volumio[7555]: info: Got Tidal Cloud Configuration
Mar 17 21:52:22 volumio volumio[7555]: info: Got it
Mar 17 21:52:22 volumio volumio[7555]: info: Got it
Mar 17 21:52:22 volumio volumio[7555]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Mar 17 21:52:22 volumio volumio[7555]: info: Asound.conf file unchanged, so no further update is needed
Mar 17 21:52:22 volumio volumio[7555]: info: Output device has changed, restarting MPD
Mar 17 21:52:22 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121
Mar 17 21:52:22 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121
Mar 17 21:52:22 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121
Mar 17 21:52:22 volumio sudo[8051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Mar 17 21:52:22 volumio sudo[8051]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 17 21:52:22 volumio sudo[8051]: pam_unix(sudo:session): session closed for user root
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 17 21:52:22 volumio volumio[7555]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Mar 17 21:52:22 volumio volumio[7555]: info: MPD Permissions set
Mar 17 21:52:22 volumio volumio[7555]: info: VolumeController:: Volume=100 Mute =true
Mar 17 21:52:22 volumio volumio[7555]: info: CoreStateMachine::pushState
Mar 17 21:52:22 volumio volumio[7555]: info: CorePlayQueue::getTrack 0
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::volumioPushState
Mar 17 21:52:22 volumio sudo[8054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Mar 17 21:52:22 volumio sudo[8054]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 17 21:52:22 volumio systemd[1]: Stopping Music Player Daemon...
Mar 17 21:52:22 volumio volumio[7555]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 17 21:52:22 volumio systemd[1]: mpd.service: Succeeded.
Mar 17 21:52:22 volumio systemd[1]: Stopped Music Player Daemon.
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 17 21:52:22 volumio systemd[1]: Starting Music Player Daemon...
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 17 21:52:22 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 17 21:52:22 volumio mpd[8056]: Mar 17 21:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Mar 17 21:52:22 volumio systemd[1]: Started Music Player Daemon.
Mar 17 21:52:22 volumio sudo[8054]: pam_unix(sudo:session): session closed for user root
Mar 17 21:52:22 volumio volumio[7555]: error: updateQueue error: null
Mar 17 21:52:23 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation
Mar 17 21:52:23 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware
Mar 17 21:52:23 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray
Mar 17 21:52:23 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage
Mar 17 21:52:23 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware
Mar 17 21:52:24 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction
Mar 17 21:52:24 volumio volumio[7555]: info: CoreCommandRouter::Reload Ui
Mar 17 21:52:24 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard
Mar 17 21:52:24 volumio volumio[7555]: info: Wizard terminated Successfully
Mar 17 21:52:24 volumio volumio[7555]: info: CoreCommandRouter::volumiosetStartupVolume
Mar 17 21:52:24 volumio volumio[7555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 17 21:52:25 volumio go-librespot[8022]: time="2025-03-17T21:52:25+08:00" level=debug msg="obtained new client token: AAAdgiBDEqEBZsaGrga4ghyicpwAVKnGHXf8y2kj+LqSqUA8KWIqjXiFxZj9FrQOarg4qSm/qOaRxwCS9X1mVy5bSVyaF6geIrcL5DeCQeCRxvkOh0+6DPSqH150I89mHYUPC65978yvK4ZF8xsgztzo2E6BsCGklKefv6ojbCjymcKuijU9jMPhXIqcfl98jfC/DpicFcdhLiORUx7dT0/x/ri7q//aYAzmOeevjLY8gMD/DZdhKotyX9dklyH4GQ=="
Mar 17 21:52:25 volumio volumio[7555]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 17 21:52:25 volumio volumio[7555]: Error: connect ETIMEDOUT 208.101.21.43:443
Mar 17 21:52:25 volumio volumio[7555]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) {
Mar 17 21:52:25 volumio volumio[7555]: errno: -110,
Mar 17 21:52:25 volumio volumio[7555]: code: 'ETIMEDOUT',
Mar 17 21:52:25 volumio volumio[7555]: syscall: 'connect',
Mar 17 21:52:25 volumio volumio[7555]: address: '208.101.21.43',
Mar 17 21:52:25 volumio volumio[7555]: port: 443
Mar 17 21:52:25 volumio volumio[7555]: }
Mar 17 21:52:25 volumio volumio[7555]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 17 21:52:26 volumio sudo[8080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-17 21:51
Mar 17 21:52:26 volumio sudo[8080]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d"
VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71"
VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1"
VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET"
VOLUMIO_VERSION="3.631"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"