-- Logs begin at Thu 2019-02-14 19:12:00 KST, end at Fri 2024-11-22 23:15:43 KST. -- Nov 22 23:14:03 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 23:14:03 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 23:14:03 volumio volumio[1211]: info: Discovery: Getting this device information Nov 22 23:14:03 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Nov 22 23:14:03 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 23:14:03 volumio volumio[1211]: verbose: New Socket.io Connection to 192.168.0.17:3000 from 192.168.0.24 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Nov 22 23:14:09 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 23:14:09 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 23:14:09 volumio volumio[1211]: info: Discovery: Getting this device information Nov 22 23:14:09 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Nov 22 23:14:09 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 23:14:39 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 23:14:39 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 23:14:39 volumio volumio[1211]: info: Discovery: Getting this device information Nov 22 23:14:39 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Nov 22 23:14:39 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 23:14:43 volumio volumio[1211]: verbose: New Socket.io Connection to 192.168.0.17:3000 from 192.168.0.24 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Nov 22 23:14:44 volumio volumio[1211]: info: CoreCommandRouter::volumioStop Nov 22 23:14:44 volumio volumio[1211]: info: CoreStateMachine::stop Nov 22 23:14:44 volumio volumio[1211]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 23:14:44 volumio volumio[1211]: info: CoreStateMachine::stPlaybackTimer Nov 22 23:14:44 volumio volumio[1211]: info: CoreStateMachine::updateTrackBlock Nov 22 23:14:44 volumio volumio[1211]: info: CorePlayQueue::getTrackBlock Nov 22 23:14:44 volumio volumio[1211]: info: CoreStateMachine::pushState Nov 22 23:14:44 volumio volumio[1211]: info: CorePlayQueue::getTrack 0 Nov 22 23:14:44 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 23:14:44 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Nov 22 23:14:44 volumio volumio[1211]: info: CoreStateMachine::serviceStop Nov 22 23:14:44 volumio volumio[1211]: info: CorePlayQueue::getTrack 0 Nov 22 23:14:44 volumio volumio[1211]: info: CoreCommandRouter::serviceStop Nov 22 23:14:44 volumio volumio[1211]: info: ControllerMpd::stop Nov 22 23:14:44 volumio volumio[1211]: verbose: ControllerMpd::sendMpdCommand stop Nov 22 23:14:44 volumio volumio[1211]: info: Nov 22 23:14:44 volumio volumio[1211]: ---------------------------- MPD announces state update: player Nov 22 23:14:44 volumio volumio[1211]: info: sendMpdCommand stop took 16 milliseconds Nov 22 23:14:44 volumio volumio[1211]: info: ControllerMpd::getState Nov 22 23:14:44 volumio volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Nov 22 23:14:44 volumio volumio[1211]: info: ControllerMpd::getState Nov 22 23:14:44 volumio volumio[1211]: verbose: ControllerMpd::sendMpdCommand status Nov 22 23:14:44 volumio volumio[1211]: info: sendMpdCommand status took 3 milliseconds Nov 22 23:14:44 volumio volumio[1211]: info: sendMpdCommand status took 3 milliseconds Nov 22 23:14:44 volumio volumio[1211]: verbose: ControllerMpd::parseState Nov 22 23:14:44 volumio volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 23:14:44 volumio volumio[1211]: verbose: ControllerMpd::parseState Nov 22 23:14:44 volumio volumio[1211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 23:14:44 volumio volumio[1211]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 22 23:14:44 volumio volumio[1211]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 22 23:14:44 volumio volumio[1211]: verbose: ControllerMpd::parseTrackInfo Nov 22 23:14:44 volumio volumio[1211]: verbose: ControllerMpd::parseTrackInfo Nov 22 23:14:44 volumio volumio[1211]: info: ControllerMpd::pushState Nov 22 23:14:44 volumio volumio[1211]: info: CoreCommandRouter::servicePushState Nov 22 23:14:44 volumio volumio[1211]: info: CoreStateMachine::pushState Nov 22 23:14:44 volumio volumio[1211]: info: CorePlayQueue::getTrack 0 Nov 22 23:14:44 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Nov 22 23:14:44 volumio volumio[1211]: info: CorePlayQueue::getTrack 0 Nov 22 23:14:44 volumio volumio[1211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Nov 22 23:14:44 volumio volumio[1211]: info: CoreStateMachine::pushState Nov 22 23:14:44 volumio volumio[1211]: info: CorePlayQueue::getTrack 0 Nov 22 23:14:44 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Nov 22 23:14:44 volumio volumio[1211]: info: CorePlayQueue::getTrack 0 Nov 22 23:14:44 volumio volumio[1211]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Scottish Chamber Orchestra - Violin Concerto No. 2 in E min [1l6]","artist":"Linn Classical","album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""} Nov 22 23:14:44 volumio volumio[1211]: verbose: CURRENT POSITION 0 Nov 22 23:14:44 volumio volumio[1211]: info: CoreStateMachine::syncState stateService stop Nov 22 23:14:44 volumio volumio[1211]: info: CoreStateMachine::syncState currentStatus stop Nov 22 23:14:44 volumio volumio[1211]: info: CoreStateMachine::pushState Nov 22 23:14:44 volumio volumio[1211]: info: CorePlayQueue::getTrack 0 Nov 22 23:14:44 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Nov 22 23:14:44 volumio volumio[1211]: info: No code Nov 22 23:14:44 volumio volumio[1211]: info: CoreStateMachine::pushState Nov 22 23:14:44 volumio volumio[1211]: info: CorePlayQueue::getTrack 0 Nov 22 23:14:44 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Nov 22 23:14:44 volumio volumio[1211]: info: ------------------------------ 35ms Nov 22 23:14:57 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Nov 22 23:14:57 volumio volumio[1211]: info: handleBrowseUri took 407 milliseconds Nov 22 23:14:57 volumio volumio[1211]: info: Preload queue cleared Nov 22 23:14:57 volumio volumio[1211]: info: Preload queue cleared Nov 22 23:15:04 volumio volumio[1211]: Searching plugin music_service/qobuz Nov 22 23:15:04 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Nov 22 23:15:05 volumio volumio[1211]: Searching plugin music_service/qobuz Nov 22 23:15:05 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Nov 22 23:15:07 volumio volumio[1211]: Searching plugin music_service/qobuz Nov 22 23:15:07 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Nov 22 23:15:07 volumio volumio[1211]: Searching plugin music_service/qobuz Nov 22 23:15:07 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Nov 22 23:15:08 volumio volumio[1211]: info: searchQOBUZUri took 3461 milliseconds Nov 22 23:15:08 volumio volumio[1211]: info: All search sources collected, pushing search results Nov 22 23:15:08 volumio volumio[1211]: Searching plugin music_service/qobuz Nov 22 23:15:08 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Nov 22 23:15:09 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 23:15:09 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 23:15:09 volumio volumio[1211]: info: Discovery: Getting this device information Nov 22 23:15:09 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Nov 22 23:15:09 volumio volumio[1211]: info: CorePlayQueue::getTrack 0 Nov 22 23:15:09 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 23:15:09 volumio volumio[1211]: Searching plugin music_service/qobuz Nov 22 23:15:09 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Nov 22 23:15:09 volumio volumio[1211]: info: searchQOBUZUri took 4102 milliseconds Nov 22 23:15:09 volumio volumio[1211]: info: All search sources collected, pushing search results Nov 22 23:15:10 volumio volumio[1211]: Searching plugin music_service/qobuz Nov 22 23:15:10 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Nov 22 23:15:10 volumio volumio[1211]: info: searchQOBUZUri took 3290 milliseconds Nov 22 23:15:10 volumio volumio[1211]: info: All search sources collected, pushing search results Nov 22 23:15:10 volumio volumio[1211]: info: searchQOBUZUri took 2384 milliseconds Nov 22 23:15:10 volumio volumio[1211]: info: All search sources collected, pushing search results Nov 22 23:15:11 volumio volumio[1211]: info: searchQOBUZUri took 4143 milliseconds Nov 22 23:15:11 volumio volumio[1211]: info: All search sources collected, pushing search results Nov 22 23:15:12 volumio volumio[1211]: info: searchQOBUZUri took 3178 milliseconds Nov 22 23:15:12 volumio volumio[1211]: info: All search sources collected, pushing search results Nov 22 23:15:12 volumio volumio[1211]: info: searchQOBUZUri took 2695 milliseconds Nov 22 23:15:12 volumio volumio[1211]: info: All search sources collected, pushing search results Nov 22 23:15:16 volumio volumio[1211]: Searching plugin music_service/qobuz Nov 22 23:15:16 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Nov 22 23:15:18 volumio volumio[1211]: info: searchQOBUZUri took 2290 milliseconds Nov 22 23:15:18 volumio volumio[1211]: info: All search sources collected, pushing search results Nov 22 23:15:26 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Nov 22 23:15:26 volumio volumio[1211]: info: handleBrowseUri took 847 milliseconds Nov 22 23:15:26 volumio volumio[1211]: info: Preload queue cleared Nov 22 23:15:26 volumio volumio[1211]: info: Preloading song: qobuz://song/49661113 Nov 22 23:15:26 volumio volumio[1211]: info: Preloading song: qobuz://song/49661114 Nov 22 23:15:26 volumio volumio[1211]: info: Preloading song: qobuz://song/49661115 Nov 22 23:15:26 volumio volumio[1211]: info: Preloading song: qobuz://song/49661116 Nov 22 23:15:26 volumio volumio[1211]: info: Preloading song: qobuz://song/49661117 Nov 22 23:15:26 volumio volumio[1211]: info: Preloading song: qobuz://song/49661118 Nov 22 23:15:26 volumio volumio[1211]: info: Preloading song: qobuz://song/49661119 Nov 22 23:15:26 volumio volumio[1211]: info: Exploding uri qobuz://song/49661113 in service qobuz Nov 22 23:15:26 volumio volumio[1211]: https://prod.vlmapi.io/v2/qobuz/explodeUri Nov 22 23:15:27 volumio volumio[1211]: info: Exploding uri qobuz://song/49661114 in service qobuz Nov 22 23:15:27 volumio volumio[1211]: https://prod.vlmapi.io/v2/qobuz/explodeUri Nov 22 23:15:27 volumio volumio[1211]: info: Executing endpoint metavolumio Nov 22 23:15:27 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 22 23:15:27 volumio volumio[1211]: info: Executing endpoint metavolumio Nov 22 23:15:27 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 22 23:15:27 volumio volumio[1211]: info: Exploding uri qobuz://song/49661115 in service qobuz Nov 22 23:15:27 volumio volumio[1211]: https://prod.vlmapi.io/v2/qobuz/explodeUri Nov 22 23:15:27 volumio volumio[1211]: info: Executing endpoint metavolumio Nov 22 23:15:27 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 22 23:15:27 volumio volumio[1211]: info: Executing endpoint metavolumio Nov 22 23:15:27 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 22 23:15:27 volumio volumio[1211]: info: Executing endpoint metavolumio Nov 22 23:15:27 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 22 23:15:27 volumio volumio[1211]: info: Executing endpoint metavolumio Nov 22 23:15:27 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 22 23:15:27 volumio volumio[1211]: info: Exploding uri qobuz://song/49661116 in service qobuz Nov 22 23:15:27 volumio volumio[1211]: https://prod.vlmapi.io/v2/qobuz/explodeUri Nov 22 23:15:27 volumio volumio[1211]: info: Exploding uri qobuz://song/49661117 in service qobuz Nov 22 23:15:27 volumio volumio[1211]: https://prod.vlmapi.io/v2/qobuz/explodeUri Nov 22 23:15:27 volumio volumio[1211]: info: Exploding uri qobuz://song/49661118 in service qobuz Nov 22 23:15:27 volumio volumio[1211]: https://prod.vlmapi.io/v2/qobuz/explodeUri Nov 22 23:15:27 volumio volumio[1211]: info: Exploding uri qobuz://song/49661119 in service qobuz Nov 22 23:15:27 volumio volumio[1211]: https://prod.vlmapi.io/v2/qobuz/explodeUri Nov 22 23:15:27 volumio volumio[1211]: info: explodeUri took 677 milliseconds Nov 22 23:15:28 volumio volumio[1211]: info: explodeUri took 741 milliseconds Nov 22 23:15:28 volumio volumio[1211]: info: explodeUri took 824 milliseconds Nov 22 23:15:28 volumio volumio[1211]: info: explodeUri took 1120 milliseconds Nov 22 23:15:28 volumio volumio[1211]: info: explodeUri took 908 milliseconds Nov 22 23:15:28 volumio volumio[1211]: info: explodeUri took 1343 milliseconds Nov 22 23:15:29 volumio volumio[1211]: info: explodeUri took 1995 milliseconds Nov 22 23:15:32 volumio volumio[1211]: info: Executing endpoint metavolumio Nov 22 23:15:32 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 22 23:15:33 volumio volumio[1211]: info: Executing endpoint metavolumio Nov 22 23:15:33 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 22 23:15:33 volumio volumio[1211]: info: Executing endpoint metavolumio Nov 22 23:15:33 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 22 23:15:34 volumio volumio[1211]: error: Failed request for metavolumio API Nov 22 23:15:34 volumio volumio[1211]: error: Failed request for metavolumio API Nov 22 23:15:34 volumio volumio[1211]: error: Failed request for metavolumio API Nov 22 23:15:34 volumio volumio[1211]: info: Listing playlists Nov 22 23:15:39 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 23:15:39 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 23:15:39 volumio volumio[1211]: info: Discovery: Getting this device information Nov 22 23:15:39 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Nov 22 23:15:39 volumio volumio[1211]: info: CorePlayQueue::getTrack 0 Nov 22 23:15:39 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 23:15:41 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: qobuz , explodeUri Nov 22 23:15:41 volumio volumio[1211]: https://prod.vlmapi.io/v2/qobuz/explodeUri Nov 22 23:15:42 volumio volumio[1211]: info: explodeUri took 306 milliseconds Nov 22 23:15:42 volumio volumio[1211]: info: Saving Cloud item Bruch Scottish fantasy violin con #1 Nov 22 23:15:42 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: my_volumio , saveCloudItem Nov 22 23:15:42 volumio volumio[1211]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 22 23:15:42 volumio volumio[1211]: Error: Reference.child failed: First argument was an invalid path = "/user_data/0X5yOZECrRhzJQ6vEt5GazYi2ls2/myPlaylists/Bruch Scottish fantasy violin con #1". Paths must be non-empty strings and can't contain ".", "#", "$", "[", or "]" Nov 22 23:15:42 volumio volumio[1211]: at validatePathString (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:1667:15) Nov 22 23:15:42 volumio volumio[1211]: at validateRootPathString (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:1679:5) Nov 22 23:15:42 volumio volumio[1211]: at Reference.child (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:13737:17) Nov 22 23:15:42 volumio volumio[1211]: at Database.ref (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:15115:48) Nov 22 23:15:42 volumio volumio[1211]: at myVolumio.syncJSONToCloud (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:28817) Nov 22 23:15:42 volumio volumio[1211]: at myVolumio.saveCloudMyPlaylist (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:23840) Nov 22 23:15:42 volumio volumio[1211]: at myVolumio.saveCloudItem (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:18337) Nov 22 23:15:42 volumio volumio[1211]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1097:32) Nov 22 23:15:42 volumio volumio[1211]: at PlaylistManager.saveJSONFile (/volumio/app/playlistManager.js:613:31) Nov 22 23:15:42 volumio volumio[1211]: at /volumio/app/playlistManager.js:588:20 Nov 22 23:15:42 volumio volumio[1211]: at /volumio/node_modules/jsonfile/index.js:46:5 Nov 22 23:15:42 volumio volumio[1211]: at /volumio/node_modules/graceful-fs/graceful-fs.js:123:16 Nov 22 23:15:42 volumio volumio[1211]: at FSReqCallback.readFileAfterClose [as oncomplete] (internal/fs/read_file_context.js:63:3) Nov 22 23:15:42 volumio volumio[1211]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 22 23:15:43 volumio sudo[24060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-22 23:14 Nov 22 23:15:43 volumio sudo[24060]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"