-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Mon 2024-11-25 18:55:04 UTC. -- Nov 25 18:54:02 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 25 18:54:02 music volumio[1087]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 25 18:54:02 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 25 18:54:02 music volumio[1087]: info: Received Get System Version Nov 25 18:54:02 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 18:54:02 music volumio[1087]: info: Received Get System Info Nov 25 18:54:02 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 25 18:54:02 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 25 18:54:02 music volumio[1087]: info: Discovery: Getting this device information Nov 25 18:54:02 music volumio[1087]: info: CoreCommandRouter::volumioGetState Nov 25 18:54:02 music volumio[1087]: info: CorePlayQueue::getTrack 0 Nov 25 18:54:02 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 25 18:54:03 music volumio[1087]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 25 18:54:03 music volumio[1087]: error: [youtube2] Error in ConfigModel.#fetchAccountMenu(): Request to https://www.youtube.com/youtubei/v1/account/account_menu?prettyPrint=false&alt=json failed with status 400 Error: Request to https://www.youtube.com/youtubei/v1/account/account_menu?prettyPrint=false&alt=json failed with status 400 Nov 25 18:54:03 music volumio[1087]: at HTTPClient. (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:9501:13) Nov 25 18:54:03 music volumio[1087]: at Generator.next () Nov 25 18:54:03 music volumio[1087]: at fulfilled (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:215:30) Nov 25 18:54:03 music volumio[1087]: at runMicrotasks () Nov 25 18:54:03 music volumio[1087]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Nov 25 18:54:03 music volumio[1087]: (node:1087) UnhandledPromiseRejectionWarning: TypeError: Cannot convert undefined or null to object Nov 25 18:54:03 music volumio[1087]: at Function.entries () Nov 25 18:54:03 music volumio[1087]: at findInObject (/data/plugins/music_service/youtube2/dist/lib/util/index.js:27:43) Nov 25 18:54:03 music volumio[1087]: at ConfigModel.getI18nOptions (/data/plugins/music_service/youtube2/dist/lib/model/ConfigModel.js:84:54) Nov 25 18:54:03 music volumio[1087]: at runMicrotasks () Nov 25 18:54:03 music volumio[1087]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Nov 25 18:54:03 music volumio[1087]: (node:1087) 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: 29) Nov 25 18:54:03 music volumio[1087]: warn: [youtube2] Failed to get account config: Error: Request to https://www.youtube.com/youtubei/v1/account/accounts_list?prettyPrint=false&alt=json failed with status 400 Nov 25 18:54:05 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 25 18:54:05 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 25 18:54:10 music volumio[1087]: info: Starting Uninstall of plugin music_service - youtube2 Nov 25 18:54:10 music volumio[1087]: info: Uninstalling plugin youtube2 Nov 25 18:54:10 music volumio[1087]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesYouTube2 Nov 25 18:54:10 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 18:54:10 music volumio[1087]: info: Disabling plugin youtube2 Nov 25 18:54:10 music volumio[1087]: info: Checking if uninstall.sh is present Nov 25 18:54:10 music volumio[1087]: info: Executing uninstall.sh Nov 25 18:54:10 music sudo[19053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/youtube2/uninstall.sh Nov 25 18:54:10 music sudo[19053]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 18:54:10 music sudo[19053]: pam_unix(sudo:session): session closed for user root Nov 25 18:54:10 music volumio[1087]: info: Uninstall script completed Nov 25 18:54:10 music volumio[1087]: info: Removing plugin youtube2 from configuration Nov 25 18:54:10 music volumio[1087]: info: Successfully removed youtube2 configuration files Nov 25 18:54:10 music volumio[1087]: info: Plugin folders cleanup Nov 25 18:54:10 music volumio[1087]: info: Scanning into folder /volumio/app/plugins/ Nov 25 18:54:10 music volumio[1087]: info: Scanning category audio_interface Nov 25 18:54:10 music volumio[1087]: info: Scanning category miscellanea Nov 25 18:54:10 music volumio[1087]: info: Scanning category music_service Nov 25 18:54:10 music volumio[1087]: info: Scanning category plugins.json Nov 25 18:54:10 music volumio[1087]: info: Scanning category system_controller Nov 25 18:54:10 music volumio[1087]: info: Scanning category user_interface Nov 25 18:54:10 music volumio[1087]: info: Scanning into folder /data/plugins/ Nov 25 18:54:10 music volumio[1087]: info: Scanning category miscellanea Nov 25 18:54:10 music volumio[1087]: info: Scanning category music_service Nov 25 18:54:10 music volumio[1087]: info: Cleaning folder for youtube2 Nov 25 18:54:11 music volumio[1087]: info: Scanning category system_controller Nov 25 18:54:11 music volumio[1087]: info: Scanning category user_interface Nov 25 18:54:11 music volumio[1087]: info: Plugin folders cleanup completed Nov 25 18:54:11 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 25 18:54:11 music volumio[1087]: info: [{"prettyName":"PeppyMeter Screensaver","name":"peppy_screensaver","category":"miscellanea","version":"1.3.0","icon":"fa-lightbulb-o","isManuallyInstalled":true,"enabled":false,"active":false},{"prettyName":"miniDLNA","name":"minidlna","category":"music_service","version":"3.1.12","icon":"fa-share-alt","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Cast Receiver","name":"ytcr","category":"music_service","version":"1.1.2","icon":"fa-youtube","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Backup & Restore Data","name":"backup_restore","category":"system_controller","version":"0.7.4","icon":"fa-floppy-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Now Playing","name":"now_playing","category":"user_interface","version":"0.7.3","icon":"fa-play-circle","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"PeppyMeter Screensaver","name":"peppy_screensaver","category":"user_interface","version":"1.4.2","icon":"fa-lightbulb-o","isManuallyInstalled":true,"enabled":false,"active":false},{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.5.1","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":true,"active":true}] Nov 25 18:54:11 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 25 18:54:19 music volumio[1087]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 25 18:54:30 music sudo[19154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 25 18:54:30 music sudo[19154]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 18:54:30 music sudo[19154]: pam_unix(sudo:session): session closed for user root Nov 25 18:54:30 music sudo[19157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 25 18:54:30 music sudo[19157]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 18:54:30 music sudo[19157]: pam_unix(sudo:session): session closed for user root Nov 25 18:54:30 music volumio[1087]: verbose: New Socket.io Connection to 192.168.1.109 from 192.168.1.101 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/127.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Nov 25 18:54:31 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 25 18:54:31 music volumio[1087]: info: CoreCommandRouter::volumioGetVisibleSources Nov 25 18:54:31 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 18:54:31 music volumio[1087]: info: CoreCommandRouter::volumioGetState Nov 25 18:54:31 music volumio[1087]: info: CorePlayQueue::getTrack 0 Nov 25 18:54:31 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 25 18:54:31 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 25 18:54:31 music volumio[1087]: info: Received Get System Info Nov 25 18:54:31 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 25 18:54:31 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 25 18:54:31 music volumio[1087]: info: Discovery: Getting this device information Nov 25 18:54:31 music volumio[1087]: info: CoreCommandRouter::volumioGetState Nov 25 18:54:31 music volumio[1087]: info: CorePlayQueue::getTrack 0 Nov 25 18:54:31 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 25 18:54:31 music volumio[1087]: info: CoreCommandRouter::volumioGetState Nov 25 18:54:31 music volumio[1087]: info: CorePlayQueue::getTrack 0 Nov 25 18:54:31 music volumio[1087]: info: Listing playlists Nov 25 18:54:31 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 25 18:54:31 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 25 18:54:31 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 25 18:54:32 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 25 18:54:32 music volumio[1087]: info: Received Get System Info Nov 25 18:54:32 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 25 18:54:32 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 25 18:54:32 music volumio[1087]: info: Discovery: Getting this device information Nov 25 18:54:32 music volumio[1087]: info: CoreCommandRouter::volumioGetState Nov 25 18:54:32 music volumio[1087]: info: CorePlayQueue::getTrack 0 Nov 25 18:54:32 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 25 18:54:32 music sudo[19160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 25 18:54:32 music sudo[19160]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 18:54:32 music sudo[19160]: pam_unix(sudo:session): session closed for user root Nov 25 18:54:32 music sudo[19163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 25 18:54:32 music sudo[19163]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 18:54:32 music sudo[19163]: pam_unix(sudo:session): session closed for user root Nov 25 18:54:32 music volumio[1087]: verbose: New Socket.io Connection to 192.168.1.109 from 192.168.1.101 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/127.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Nov 25 18:54:33 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 25 18:54:33 music volumio[1087]: info: CoreCommandRouter::volumioGetVisibleSources Nov 25 18:54:33 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 18:54:33 music volumio[1087]: info: CoreCommandRouter::volumioGetState Nov 25 18:54:33 music volumio[1087]: info: CorePlayQueue::getTrack 0 Nov 25 18:54:33 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 25 18:54:33 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 25 18:54:33 music volumio[1087]: info: Received Get System Info Nov 25 18:54:33 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 25 18:54:33 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 25 18:54:33 music volumio[1087]: info: Discovery: Getting this device information Nov 25 18:54:33 music volumio[1087]: info: CoreCommandRouter::volumioGetState Nov 25 18:54:33 music volumio[1087]: info: CorePlayQueue::getTrack 0 Nov 25 18:54:33 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 25 18:54:33 music volumio[1087]: info: CoreCommandRouter::volumioGetState Nov 25 18:54:33 music volumio[1087]: info: CorePlayQueue::getTrack 0 Nov 25 18:54:33 music volumio[1087]: info: Listing playlists Nov 25 18:54:33 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 25 18:54:33 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 25 18:54:33 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 25 18:54:33 music volumio[1087]: info: CoreCommandRouter::volumioGetState Nov 25 18:54:33 music volumio[1087]: info: CorePlayQueue::getTrack 0 Nov 25 18:54:34 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 25 18:54:34 music volumio[1087]: info: Received Get System Info Nov 25 18:54:34 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 25 18:54:34 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 25 18:54:34 music volumio[1087]: info: Discovery: Getting this device information Nov 25 18:54:34 music volumio[1087]: info: CoreCommandRouter::volumioGetState Nov 25 18:54:34 music volumio[1087]: info: CorePlayQueue::getTrack 0 Nov 25 18:54:34 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 25 18:54:34 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 25 18:54:35 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 25 18:54:35 music volumio[1087]: info: Received Get System Info Nov 25 18:54:35 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 25 18:54:35 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 25 18:54:35 music volumio[1087]: info: Discovery: Getting this device information Nov 25 18:54:35 music volumio[1087]: info: CoreCommandRouter::volumioGetState Nov 25 18:54:35 music volumio[1087]: info: CorePlayQueue::getTrack 0 Nov 25 18:54:35 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 25 18:54:36 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 25 18:54:36 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 25 18:54:42 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 25 18:54:43 music volumio[1087]: info: CoreCommandRouter::volumioPlay Nov 25 18:54:43 music volumio[1087]: info: CoreStateMachine::play index undefined Nov 25 18:54:43 music volumio[1087]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 25 18:54:43 music volumio[1087]: info: CorePlayQueue::getTrack 0 Nov 25 18:54:43 music volumio[1087]: info: CoreStateMachine::startPlaybackTimer Nov 25 18:54:43 music volumio[1087]: info: CorePlayQueue::getTrack 0 Nov 25 18:54:43 music volumio[1087]: info: [1732560883294] ControllerWebradio::clearAddPlayTrack Nov 25 18:54:43 music volumio[1087]: verbose: ControllerMpd::sendMpdCommand stop Nov 25 18:54:43 music volumio[1087]: info: sendMpdCommand stop took 1 milliseconds Nov 25 18:54:43 music volumio[1087]: verbose: ControllerMpd::sendMpdCommand clear Nov 25 18:54:43 music volumio[1087]: info: Nov 25 18:54:43 music volumio[1087]: ---------------------------- MPD announces system playlist update Nov 25 18:54:43 music volumio[1087]: info: Ignoring MPD Status Update Nov 25 18:54:43 music volumio[1087]: info: sendMpdCommand clear took 1 milliseconds Nov 25 18:54:43 music volumio[1087]: verbose: ControllerMpd::sendMpdCommand load "https://stric6.streamakaci.com/radiomarianotehau.mp3" Nov 25 18:54:43 music volumio[1087]: info: Nov 25 18:54:43 music volumio[1087]: ---------------------------- MPD announces system playlist update Nov 25 18:54:43 music volumio[1087]: info: Ignoring MPD Status Update Nov 25 18:54:43 music volumio[1087]: error: updateQueue error: null Nov 25 18:54:43 music volumio[1087]: info: ------------------------------ 4ms Nov 25 18:54:46 music volumio[1087]: verbose: ControllerMpd::sendMpdCommand add "https://stric6.streamakaci.com/radiomarianotehau.mp3" Nov 25 18:54:46 music volumio[1087]: error: updateQueue error: null Nov 25 18:54:46 music volumio[1087]: info: ------------------------------ 2822ms Nov 25 18:54:46 music volumio[1087]: info: Nov 25 18:54:46 music volumio[1087]: ---------------------------- MPD announces system playlist update Nov 25 18:54:46 music volumio[1087]: info: Ignoring MPD Status Update Nov 25 18:54:46 music volumio[1087]: info: sendMpdCommand add "https://stric6.streamakaci.com/radiomarianotehau.mp3" took 1 milliseconds Nov 25 18:54:46 music volumio[1087]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 25 18:54:46 music volumio[1087]: verbose: ControllerMpd::sendMpdCommand play Nov 25 18:54:46 music volumio[1087]: info: Nov 25 18:54:46 music volumio[1087]: ---------------------------- MPD announces system playlist update Nov 25 18:54:46 music volumio[1087]: info: Ignoring MPD Status Update Nov 25 18:54:46 music volumio[1087]: info: ------------------------------ 3ms Nov 25 18:54:46 music volumio[1087]: info: sendMpdCommand play took 2 milliseconds Nov 25 18:54:46 music volumio[1087]: info: ------------------------------ 2ms Nov 25 18:54:46 music volumio[1087]: info: CoreCommandRouter::volumioGetState Nov 25 18:54:48 music volumio[1087]: info: Nov 25 18:54:48 music volumio[1087]: ---------------------------- MPD announces state update: player Nov 25 18:54:48 music volumio[1087]: info: ControllerMpd::getState Nov 25 18:54:48 music volumio[1087]: verbose: ControllerMpd::sendMpdCommand status Nov 25 18:54:48 music volumio[1087]: info: Nov 25 18:54:48 music volumio[1087]: ---------------------------- MPD announces state update: player Nov 25 18:54:48 music volumio[1087]: info: ControllerMpd::getState Nov 25 18:54:48 music volumio[1087]: verbose: ControllerMpd::sendMpdCommand status Nov 25 18:54:48 music volumio[1087]: info: Nov 25 18:54:48 music volumio[1087]: ---------------------------- MPD announces state update: player Nov 25 18:54:48 music volumio[1087]: info: sendMpdCommand status took 3 milliseconds Nov 25 18:54:48 music volumio[1087]: info: ControllerMpd::getState Nov 25 18:54:48 music volumio[1087]: verbose: ControllerMpd::sendMpdCommand status Nov 25 18:54:48 music volumio[1087]: info: sendMpdCommand status took 2 milliseconds Nov 25 18:54:48 music volumio[1087]: verbose: ControllerMpd::parseState Nov 25 18:54:48 music volumio[1087]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 25 18:54:48 music volumio[1087]: verbose: ControllerMpd::parseState Nov 25 18:54:48 music volumio[1087]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 25 18:54:48 music volumio[1087]: info: Nov 25 18:54:48 music volumio[1087]: ---------------------------- MPD announces state update: player Nov 25 18:54:48 music volumio[1087]: info: ControllerMpd::getState Nov 25 18:54:48 music volumio[1087]: verbose: ControllerMpd::sendMpdCommand status Nov 25 18:54:48 music volumio[1087]: info: sendMpdCommand status took 4 milliseconds Nov 25 18:54:48 music volumio[1087]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 25 18:54:48 music volumio[1087]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 25 18:54:48 music volumio[1087]: info: sendMpdCommand status took 1 milliseconds Nov 25 18:54:48 music volumio[1087]: verbose: ControllerMpd::parseState Nov 25 18:54:48 music volumio[1087]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 25 18:54:48 music volumio[1087]: verbose: ControllerMpd::parseTrackInfo Nov 25 18:54:48 music volumio[1087]: verbose: ControllerMpd::parseTrackInfo Nov 25 18:54:48 music volumio[1087]: verbose: ControllerMpd::parseState Nov 25 18:54:48 music volumio[1087]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 25 18:54:48 music volumio[1087]: info: ControllerMpd::pushState Nov 25 18:54:48 music volumio[1087]: info: CoreCommandRouter::servicePushState Nov 25 18:54:48 music volumio[1087]: info: CorePlayQueue::getTrack 0 Nov 25 18:54:48 music volumio[1087]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiomarianotehau.mp3","artist":null,"album":null,"uri":"https://stric6.streamakaci.com/radiomarianotehau.mp3","trackType":"mp3"} Nov 25 18:54:48 music volumio[1087]: verbose: CURRENT POSITION 0 Nov 25 18:54:48 music volumio[1087]: info: CoreStateMachine::syncState stateService play Nov 25 18:54:48 music volumio[1087]: info: CoreStateMachine::syncState currentStatus stop Nov 25 18:54:48 music volumio[1087]: info: ControllerMpd::pushState Nov 25 18:54:48 music volumio[1087]: info: CoreCommandRouter::servicePushState Nov 25 18:54:48 music volumio[1087]: info: CorePlayQueue::getTrack 0 Nov 25 18:54:48 music volumio[1087]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiomarianotehau.mp3","artist":null,"album":null,"uri":"https://stric6.streamakaci.com/radiomarianotehau.mp3","trackType":"mp3"} Nov 25 18:54:48 music volumio[1087]: verbose: CURRENT POSITION 0 Nov 25 18:54:48 music volumio[1087]: info: CoreStateMachine::syncState stateService play Nov 25 18:54:48 music volumio[1087]: info: CoreStateMachine::syncState currentStatus play Nov 25 18:54:48 music volumio[1087]: info: Received an update from plugin. extracting info from payload Nov 25 18:54:48 music volumio[1087]: info: CoreStateMachine::pushState Nov 25 18:54:48 music volumio[1087]: info: CorePlayQueue::getTrack 0 Nov 25 18:54:48 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 18:54:48 music volumio[1087]: info: CoreCommandRouter::volumioPushState Nov 25 18:54:48 music volumio[1087]: info: CoreStateMachine::pushState Nov 25 18:54:48 music volumio[1087]: info: CoreCommandRouter::volumioPushState Nov 25 18:54:48 music volumio[1087]: info: ------------------------------ 19ms Nov 25 18:54:48 music volumio[1087]: info: ------------------------------ 32ms Nov 25 18:54:48 music volumio[1087]: info: sendMpdCommand playlistinfo took 26 milliseconds Nov 25 18:54:48 music volumio[1087]: info: sendMpdCommand playlistinfo took 25 milliseconds Nov 25 18:54:48 music volumio[1087]: verbose: ControllerMpd::parseTrackInfo Nov 25 18:54:48 music volumio[1087]: verbose: ControllerMpd::parseTrackInfo Nov 25 18:54:48 music volumio[1087]: info: ControllerMpd::pushState Nov 25 18:54:48 music volumio[1087]: info: CoreCommandRouter::servicePushState Nov 25 18:54:48 music volumio[1087]: info: CorePlayQueue::getTrack 0 Nov 25 18:54:48 music volumio[1087]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiomarianotehau.mp3","artist":null,"album":null,"uri":"https://stric6.streamakaci.com/radiomarianotehau.mp3","trackType":"mp3"} Nov 25 18:54:48 music volumio[1087]: verbose: CURRENT POSITION 0 Nov 25 18:54:48 music volumio[1087]: info: CoreStateMachine::syncState stateService play Nov 25 18:54:48 music volumio[1087]: info: CoreStateMachine::syncState currentStatus play Nov 25 18:54:48 music volumio[1087]: info: Received an update from plugin. extracting info from payload Nov 25 18:54:48 music volumio[1087]: info: CoreStateMachine::pushState Nov 25 18:54:48 music volumio[1087]: info: CorePlayQueue::getTrack 0 Nov 25 18:54:48 music volumio[1087]: info: CoreCommandRouter::volumioPushState Nov 25 18:54:48 music volumio[1087]: info: CoreStateMachine::pushState Nov 25 18:54:48 music volumio[1087]: info: CoreCommandRouter::volumioPushState Nov 25 18:54:48 music volumio[1087]: info: ControllerMpd::pushState Nov 25 18:54:48 music volumio[1087]: info: CoreCommandRouter::servicePushState Nov 25 18:54:48 music volumio[1087]: info: CorePlayQueue::getTrack 0 Nov 25 18:54:48 music volumio[1087]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiomarianotehau.mp3","artist":null,"album":null,"uri":"https://stric6.streamakaci.com/radiomarianotehau.mp3","trackType":"mp3"} Nov 25 18:54:48 music volumio[1087]: verbose: CURRENT POSITION 0 Nov 25 18:54:48 music volumio[1087]: info: CoreStateMachine::syncState stateService play Nov 25 18:54:48 music volumio[1087]: info: CoreStateMachine::syncState currentStatus play Nov 25 18:54:48 music volumio[1087]: info: Received an update from plugin. extracting info from payload Nov 25 18:54:48 music volumio[1087]: info: CoreStateMachine::pushState Nov 25 18:54:48 music volumio[1087]: info: CorePlayQueue::getTrack 0 Nov 25 18:54:48 music volumio[1087]: info: CoreCommandRouter::volumioPushState Nov 25 18:54:48 music volumio[1087]: info: CoreStateMachine::pushState Nov 25 18:54:48 music volumio[1087]: info: CoreCommandRouter::volumioPushState Nov 25 18:54:48 music volumio[1087]: info: ------------------------------ 73ms Nov 25 18:54:48 music volumio[1087]: info: ------------------------------ 71ms Nov 25 18:54:48 music volumio[1087]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 25 18:54:48 music volumio[1087]: info: Nov 25 18:54:48 music volumio[1087]: ---------------------------- MPD announces system playlist update Nov 25 18:54:48 music volumio[1087]: info: Ignoring MPD Status Update Nov 25 18:54:48 music volumio[1087]: info: Nov 25 18:54:48 music volumio[1087]: ---------------------------- MPD announces state update: player Nov 25 18:54:48 music volumio[1087]: info: ControllerMpd::getState Nov 25 18:54:48 music volumio[1087]: verbose: ControllerMpd::sendMpdCommand status Nov 25 18:54:48 music volumio[1087]: info: Nov 25 18:54:48 music volumio[1087]: ---------------------------- MPD announces system playlist update Nov 25 18:54:48 music volumio[1087]: info: Ignoring MPD Status Update Nov 25 18:54:48 music volumio[1087]: info: Nov 25 18:54:48 music volumio[1087]: ---------------------------- MPD announces state update: player Nov 25 18:54:48 music volumio[1087]: info: ControllerMpd::getState Nov 25 18:54:48 music volumio[1087]: verbose: ControllerMpd::sendMpdCommand status Nov 25 18:54:48 music volumio[1087]: info: ------------------------------ 5ms Nov 25 18:54:48 music volumio[1087]: info: sendMpdCommand status took 3 milliseconds Nov 25 18:54:48 music volumio[1087]: info: ------------------------------ 3ms Nov 25 18:54:48 music volumio[1087]: info: sendMpdCommand status took 2 milliseconds Nov 25 18:54:48 music volumio[1087]: verbose: ControllerMpd::parseState Nov 25 18:54:48 music volumio[1087]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 25 18:54:48 music volumio[1087]: verbose: ControllerMpd::parseState Nov 25 18:54:48 music volumio[1087]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 25 18:54:48 music volumio[1087]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 25 18:54:48 music volumio[1087]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 25 18:54:48 music volumio[1087]: verbose: ControllerMpd::parseTrackInfo Nov 25 18:54:48 music volumio[1087]: verbose: ControllerMpd::parseTrackInfo Nov 25 18:54:48 music volumio[1087]: info: ControllerMpd::pushState Nov 25 18:54:48 music volumio[1087]: info: CoreCommandRouter::servicePushState Nov 25 18:54:48 music volumio[1087]: info: CorePlayQueue::getTrack 0 Nov 25 18:54:48 music volumio[1087]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"radiomarianotehau.mp3","artist":"My Station name","album":null,"uri":"https://stric6.streamakaci.com/radiomarianotehau.mp3","trackType":"mp3"} Nov 25 18:54:48 music volumio[1087]: verbose: CURRENT POSITION 0 Nov 25 18:54:48 music volumio[1087]: info: CoreStateMachine::syncState stateService play Nov 25 18:54:48 music volumio[1087]: info: CoreStateMachine::syncState currentStatus play Nov 25 18:54:48 music volumio[1087]: info: Received an update from plugin. extracting info from payload Nov 25 18:54:48 music volumio[1087]: info: CoreStateMachine::pushState Nov 25 18:54:48 music volumio[1087]: info: CorePlayQueue::getTrack 0 Nov 25 18:54:48 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 18:54:48 music volumio[1087]: info: CoreCommandRouter::volumioPushState Nov 25 18:54:48 music volumio[1087]: info: CoreStateMachine::pushState Nov 25 18:54:48 music volumio[1087]: info: CoreCommandRouter::volumioPushState Nov 25 18:54:48 music volumio[1087]: info: ControllerMpd::pushState Nov 25 18:54:48 music volumio[1087]: info: CoreCommandRouter::servicePushState Nov 25 18:54:48 music volumio[1087]: info: CorePlayQueue::getTrack 0 Nov 25 18:54:48 music volumio[1087]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"radiomarianotehau.mp3","artist":"My Station name","album":null,"uri":"https://stric6.streamakaci.com/radiomarianotehau.mp3","trackType":"mp3"} Nov 25 18:54:48 music volumio[1087]: verbose: CURRENT POSITION 0 Nov 25 18:54:48 music volumio[1087]: info: CoreStateMachine::syncState stateService play Nov 25 18:54:48 music volumio[1087]: info: CoreStateMachine::syncState currentStatus play Nov 25 18:54:48 music volumio[1087]: info: Received an update from plugin. extracting info from payload Nov 25 18:54:48 music volumio[1087]: info: CoreStateMachine::pushState Nov 25 18:54:48 music volumio[1087]: info: CorePlayQueue::getTrack 0 Nov 25 18:54:48 music volumio[1087]: info: CoreCommandRouter::volumioPushState Nov 25 18:54:48 music volumio[1087]: info: CoreStateMachine::pushState Nov 25 18:54:48 music volumio[1087]: info: CoreCommandRouter::volumioPushState Nov 25 18:54:48 music volumio[1087]: info: ------------------------------ 42ms Nov 25 18:54:48 music volumio[1087]: info: ------------------------------ 41ms Nov 25 18:54:50 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 25 18:54:50 music volumio[1087]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 25 18:54:50 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 25 18:54:50 music volumio[1087]: info: Received Get System Version Nov 25 18:54:50 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 18:54:50 music volumio[1087]: info: Received Get System Info Nov 25 18:54:50 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 25 18:54:50 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 25 18:54:50 music volumio[1087]: info: Discovery: Getting this device information Nov 25 18:54:50 music volumio[1087]: info: CoreCommandRouter::volumioGetState Nov 25 18:54:50 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 25 18:54:52 music volumio[1087]: info: CoreCommandRouter::volumioGetQueue Nov 25 18:54:52 music volumio[1087]: info: CoreStateMachine::getQueue Nov 25 18:54:52 music volumio[1087]: info: CorePlayQueue::getQueue Nov 25 18:54:55 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 25 18:54:55 music volumio[1087]: info: Preload queue cleared Nov 25 18:54:59 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 25 18:54:59 music volumio[1087]: info: Preload queue cleared Nov 25 18:55:01 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 25 18:55:01 music volumio[1087]: info: Preload queue cleared Nov 25 18:55:03 music volumio[1087]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 25 18:55:03 music volumio[1087]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 18:55:03 music volumio[1087]: TypeError: Cannot read property 'length' of undefined Nov 25 18:55:03 music volumio[1087]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Nov 25 18:55:03 music volumio[1087]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Nov 25 18:55:03 music volumio[1087]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Nov 25 18:55:03 music volumio[1087]: at Parser.emit (events.js:315:20) Nov 25 18:55:03 music volumio[1087]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Nov 25 18:55:03 music volumio[1087]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Nov 25 18:55:03 music volumio[1087]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Nov 25 18:55:03 music volumio[1087]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Nov 25 18:55:03 music volumio[1087]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Nov 25 18:55:03 music volumio[1087]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Nov 25 18:55:03 music volumio[1087]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Nov 25 18:55:03 music volumio[1087]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Nov 25 18:55:03 music volumio[1087]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Nov 25 18:55:03 music volumio[1087]: at IncomingMessage.emit (events.js:327:22) Nov 25 18:55:03 music volumio[1087]: at endReadableNT (internal/streams/readable.js:1327:12) Nov 25 18:55:03 music volumio[1087]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 25 18:55:03 music volumio[1087]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 18:55:04 music sudo[19236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-25 18:54 Nov 25 18:55:04 music sudo[19236]: 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"