-- Logs begin at Thu 2019-02-14 12:12:00 EET, end at Fri 2024-11-22 01:59:58 EET. -- Nov 22 01:58:02 volumio2 sudo[3340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 22 01:58:02 volumio2 sudo[3338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 22 01:58:02 volumio2 sudo[3340]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 01:58:02 volumio2 sudo[3338]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 01:58:02 volumio2 sudo[3340]: pam_unix(sudo:session): session closed for user root Nov 22 01:58:02 volumio2 sudo[3338]: pam_unix(sudo:session): session closed for user root Nov 22 01:58:02 volumio2 volumio[1014]: verbose: New Socket.io Connection to 192.168.1.135 from 192.168.1.130 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 OPR/114.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Nov 22 01:58:02 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 22 01:58:02 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 22 01:58:03 volumio2 volumio[1014]: info: CoreCommandRouter::volumioGetVisibleSources Nov 22 01:58:03 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 01:58:03 volumio2 volumio[1014]: info: CoreCommandRouter::volumioGetState Nov 22 01:58:03 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 22 01:58:03 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 22 01:58:03 volumio2 volumio[1014]: info: Received Get System Info Nov 22 01:58:03 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 01:58:03 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 01:58:03 volumio2 volumio[1014]: info: Discovery: Getting this device information Nov 22 01:58:03 volumio2 volumio[1014]: info: CoreCommandRouter::volumioGetState Nov 22 01:58:03 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 01:58:03 volumio2 volumio[1014]: info: CoreCommandRouter::volumioGetState Nov 22 01:58:03 volumio2 volumio[1014]: info: Listing playlists Nov 22 01:58:03 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 22 01:58:03 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 22 01:58:03 volumio2 volumio[1014]: info: CoreCommandRouter::volumioGetState Nov 22 01:58:03 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 22 01:58:03 volumio2 volumio[1014]: info: Received Get System Info Nov 22 01:58:03 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 01:58:03 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 01:58:03 volumio2 volumio[1014]: info: Discovery: Getting this device information Nov 22 01:58:03 volumio2 volumio[1014]: info: CoreCommandRouter::volumioGetState Nov 22 01:58:03 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 01:58:03 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 22 01:58:04 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 22 01:58:05 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 22 01:58:05 volumio2 volumio[1014]: info: Received Get System Info Nov 22 01:58:05 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 01:58:05 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 01:58:05 volumio2 volumio[1014]: info: Discovery: Getting this device information Nov 22 01:58:05 volumio2 volumio[1014]: info: CoreCommandRouter::volumioGetState Nov 22 01:58:05 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 01:58:06 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 22 01:58:06 volumio2 volumio[1014]: info: Received Get System Info Nov 22 01:58:06 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 01:58:06 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 01:58:06 volumio2 volumio[1014]: info: Discovery: Getting this device information Nov 22 01:58:06 volumio2 volumio[1014]: info: CoreCommandRouter::volumioGetState Nov 22 01:58:06 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 01:58:12 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 22 01:58:12 volumio2 volumio[1014]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 22 01:58:12 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 22 01:58:12 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 01:58:12 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 22 01:58:12 volumio2 volumio[1014]: info: Getting Alsa Cards List without I2S DAC Nov 22 01:58:12 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Nov 22 01:58:12 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 22 01:58:12 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 01:58:12 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 01:58:12 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 01:58:12 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 01:58:12 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 01:58:12 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 01:58:12 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 22 01:58:12 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 22 01:58:12 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 22 01:58:20 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 22 01:58:20 volumio2 volumio[1014]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 22 01:58:20 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 22 01:58:20 volumio2 volumio[1014]: info: Received Get System Version Nov 22 01:58:20 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 22 01:58:20 volumio2 volumio[1014]: info: Received Get System Info Nov 22 01:58:20 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 01:58:20 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 01:58:20 volumio2 volumio[1014]: info: Discovery: Getting this device information Nov 22 01:58:20 volumio2 volumio[1014]: info: CoreCommandRouter::volumioGetState Nov 22 01:58:20 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 01:58:27 volumio2 volumio[1014]: info: CoreCommandRouter::volumioGetState Nov 22 01:58:31 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 22 01:58:31 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 22 01:58:43 volumio2 volumio[1014]: info: [ASDebug] Port: 24 Nov 22 01:58:43 volumio2 volumio[1014]: info: [ASDebug] Inverted: true Nov 22 01:58:43 volumio2 volumio[1014]: info: [ASDebug] Delay: 720 Nov 22 01:59:21 volumio2 volumio[1014]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 22 01:59:21 volumio2 volumio[1014]: info: [youtube2] InnertubeLoader: creating Innertube instance... Nov 22 01:59:29 volumio2 volumio[1014]: info: [youtube2] InnertubeLoader: creating Auth instance... Nov 22 01:59:29 volumio2 volumio[1014]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 22 01:59:29 volumio2 volumio[1014]: info: [youtube2] Auth success Nov 22 01:59:29 volumio2 volumio[1014]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 22 01:59:29 volumio2 volumio[1014]: info: [youtube2] InnertubeLoader: Auth instance created (status: signed in) Nov 22 01:59:29 volumio2 volumio[1014]: 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 22 01:59:29 volumio2 volumio[1014]: 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 22 01:59:29 volumio2 volumio[1014]: 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 22 01:59:29 volumio2 volumio[1014]: at HTTPClient. (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:9501:13) Nov 22 01:59:29 volumio2 volumio[1014]: at Generator.next () Nov 22 01:59:29 volumio2 volumio[1014]: at fulfilled (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:215:30) Nov 22 01:59:29 volumio2 volumio[1014]: at runMicrotasks () Nov 22 01:59:29 volumio2 volumio[1014]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Nov 22 01:59:29 volumio2 volumio[1014]: (node:1014) UnhandledPromiseRejectionWarning: TypeError: Cannot convert undefined or null to object Nov 22 01:59:29 volumio2 volumio[1014]: at Function.entries () Nov 22 01:59:29 volumio2 volumio[1014]: at findInObject (/data/plugins/music_service/youtube2/dist/lib/util/index.js:27:43) Nov 22 01:59:29 volumio2 volumio[1014]: at ConfigModel.getI18nOptions (/data/plugins/music_service/youtube2/dist/lib/model/ConfigModel.js:84:54) Nov 22 01:59:29 volumio2 volumio[1014]: at runMicrotasks () Nov 22 01:59:29 volumio2 volumio[1014]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Nov 22 01:59:29 volumio2 volumio[1014]: (node:1014) 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: 4) Nov 22 01:59:29 volumio2 volumio[1014]: 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 22 01:59:29 volumio2 volumio[1014]: at HTTPClient. (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:9501:13) Nov 22 01:59:29 volumio2 volumio[1014]: at Generator.next () Nov 22 01:59:29 volumio2 volumio[1014]: at fulfilled (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:215:30) Nov 22 01:59:29 volumio2 volumio[1014]: at runMicrotasks () Nov 22 01:59:29 volumio2 volumio[1014]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Nov 22 01:59:29 volumio2 volumio[1014]: (node:1014) UnhandledPromiseRejectionWarning: TypeError: Cannot convert undefined or null to object Nov 22 01:59:29 volumio2 volumio[1014]: at Function.entries () Nov 22 01:59:29 volumio2 volumio[1014]: at findInObject (/data/plugins/music_service/youtube2/dist/lib/util/index.js:27:43) Nov 22 01:59:29 volumio2 volumio[1014]: at ConfigModel.getI18nOptions (/data/plugins/music_service/youtube2/dist/lib/model/ConfigModel.js:84:54) Nov 22 01:59:29 volumio2 volumio[1014]: at runMicrotasks () Nov 22 01:59:29 volumio2 volumio[1014]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Nov 22 01:59:29 volumio2 volumio[1014]: (node:1014) 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: 5) Nov 22 01:59:29 volumio2 volumio[1014]: 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 22 01:59:29 volumio2 volumio[1014]: at HTTPClient. (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:9501:13) Nov 22 01:59:29 volumio2 volumio[1014]: at Generator.next () Nov 22 01:59:29 volumio2 volumio[1014]: at fulfilled (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:215:30) Nov 22 01:59:29 volumio2 volumio[1014]: at runMicrotasks () Nov 22 01:59:29 volumio2 volumio[1014]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Nov 22 01:59:29 volumio2 volumio[1014]: (node:1014) UnhandledPromiseRejectionWarning: TypeError: Cannot convert undefined or null to object Nov 22 01:59:29 volumio2 volumio[1014]: at Function.entries () Nov 22 01:59:29 volumio2 volumio[1014]: at findInObject (/data/plugins/music_service/youtube2/dist/lib/util/index.js:27:43) Nov 22 01:59:29 volumio2 volumio[1014]: at ConfigModel.getI18nOptions (/data/plugins/music_service/youtube2/dist/lib/model/ConfigModel.js:84:54) Nov 22 01:59:29 volumio2 volumio[1014]: at runMicrotasks () Nov 22 01:59:29 volumio2 volumio[1014]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Nov 22 01:59:29 volumio2 volumio[1014]: (node:1014) 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: 6) Nov 22 01:59:29 volumio2 volumio[1014]: 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 22 01:59:44 volumio2 volumio[1014]: info: CoreCommandRouter::volumioGetState Nov 22 01:59:50 volumio2 volumio[1014]: info: CoreCommandRouter::volumioGetQueue Nov 22 01:59:50 volumio2 volumio[1014]: info: CoreStateMachine::getQueue Nov 22 01:59:50 volumio2 volumio[1014]: info: CorePlayQueue::getQueue Nov 22 01:59:56 volumio2 volumio[1014]: info: CoreCommandRouter::volumioPlay Nov 22 01:59:56 volumio2 volumio[1014]: verbose: UNSET VOLATILE: Service: ytcr Nov 22 01:59:56 volumio2 volumio[1014]: info: CoreCommandRouter::servicePushState Nov 22 01:59:56 volumio2 volumio[1014]: info: CoreStateMachine::pushState Nov 22 01:59:56 volumio2 volumio[1014]: info: CoreCommandRouter::volumioPushState Nov 22 01:59:56 volumio2 volumio[1014]: info: CoreCommandRouter::servicePushState Nov 22 01:59:56 volumio2 volumio[1014]: info: CoreStateMachine::pushState Nov 22 01:59:56 volumio2 volumio[1014]: info: CoreCommandRouter::volumioPushState Nov 22 01:59:56 volumio2 volumio[1014]: info: CoreStateMachine::play index 0 Nov 22 01:59:56 volumio2 volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 01:59:56 volumio2 volumio[1014]: info: CoreStateMachine::stop Nov 22 01:59:56 volumio2 volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 01:59:56 volumio2 volumio[1014]: info: CoreStateMachine::updateTrackBlock Nov 22 01:59:56 volumio2 volumio[1014]: info: CorePlayQueue::getTrackBlock Nov 22 01:59:56 volumio2 volumio[1014]: info: CoreStateMachine::stPlaybackTimer Nov 22 01:59:56 volumio2 volumio[1014]: info: CoreStateMachine::pushState Nov 22 01:59:56 volumio2 volumio[1014]: info: CorePlayQueue::getTrack 0 Nov 22 01:59:56 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 01:59:56 volumio2 volumio[1014]: info: CoreCommandRouter::volumioPushState Nov 22 01:59:56 volumio2 volumio[1014]: info: CoreStateMachine::serviceStop Nov 22 01:59:56 volumio2 volumio[1014]: info: CorePlayQueue::getTrack 0 Nov 22 01:59:56 volumio2 volumio[1014]: info: CoreCommandRouter::serviceStop Nov 22 01:59:56 volumio2 volumio[1014]: info: [1732233596308] ControllerWebradio::stop Nov 22 01:59:56 volumio2 volumio[1014]: verbose: ControllerMpd::sendMpdCommand stop Nov 22 01:59:56 volumio2 volumio[1014]: info: [ASDebug] CurState: pause PrevState: stop Nov 22 01:59:56 volumio2 volumio[1014]: info: [ASDebug] CurState: pause PrevState: stop Nov 22 01:59:56 volumio2 volumio[1014]: info: [ASDebug] CurState: stop PrevState: stop Nov 22 01:59:56 volumio2 volumio[1014]: info: sendMpdCommand stop took 39 milliseconds Nov 22 01:59:56 volumio2 volumio[1014]: info: CoreStateMachine::play index undefined Nov 22 01:59:56 volumio2 volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 01:59:56 volumio2 volumio[1014]: info: CorePlayQueue::getTrack 0 Nov 22 01:59:56 volumio2 volumio[1014]: info: CoreStateMachine::startPlaybackTimer Nov 22 01:59:56 volumio2 volumio[1014]: info: CorePlayQueue::getTrack 0 Nov 22 01:59:56 volumio2 volumio[1014]: info: [1732233596352] ControllerWebradio::clearAddPlayTrack Nov 22 01:59:56 volumio2 volumio[1014]: verbose: ControllerMpd::sendMpdCommand stop Nov 22 01:59:56 volumio2 volumio[1014]: info: sendMpdCommand stop took 5 milliseconds Nov 22 01:59:56 volumio2 volumio[1014]: verbose: ControllerMpd::sendMpdCommand clear Nov 22 01:59:56 volumio2 volumio[1014]: info: Nov 22 01:59:56 volumio2 volumio[1014]: ---------------------------- MPD announces system playlist update Nov 22 01:59:56 volumio2 volumio[1014]: info: Ignoring MPD Status Update Nov 22 01:59:56 volumio2 volumio[1014]: info: sendMpdCommand clear took 10 milliseconds Nov 22 01:59:56 volumio2 volumio[1014]: verbose: ControllerMpd::sendMpdCommand load "http://live.dancemusic.ro:7000/" Nov 22 01:59:56 volumio2 volumio[1014]: info: Nov 22 01:59:56 volumio2 volumio[1014]: ---------------------------- MPD announces system playlist update Nov 22 01:59:56 volumio2 volumio[1014]: info: Ignoring MPD Status Update Nov 22 01:59:56 volumio2 volumio[1014]: info: Nov 22 01:59:56 volumio2 volumio[1014]: ---------------------------- MPD announces system playlist update Nov 22 01:59:56 volumio2 volumio[1014]: info: Ignoring MPD Status Update Nov 22 01:59:56 volumio2 volumio[1014]: error: Upnp client error: Error: This socket has been ended by the other party Nov 22 01:59:57 volumio2 volumio[1014]: error: updateQueue error: null Nov 22 01:59:57 volumio2 volumio[1014]: info: ------------------------------ 793ms Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::sendMpdCommand add "http://live.dancemusic.ro:7000/" Nov 22 01:59:57 volumio2 volumio[1014]: error: updateQueue error: null Nov 22 01:59:57 volumio2 volumio[1014]: error: updateQueue error: null Nov 22 01:59:57 volumio2 volumio[1014]: info: Nov 22 01:59:57 volumio2 volumio[1014]: ---------------------------- MPD announces system playlist update Nov 22 01:59:57 volumio2 volumio[1014]: info: Ignoring MPD Status Update Nov 22 01:59:57 volumio2 volumio[1014]: info: ------------------------------ 787ms Nov 22 01:59:57 volumio2 volumio[1014]: info: ------------------------------ 785ms Nov 22 01:59:57 volumio2 volumio[1014]: info: sendMpdCommand add "http://live.dancemusic.ro:7000/" took 5 milliseconds Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::sendMpdCommand play Nov 22 01:59:57 volumio2 volumio[1014]: info: Nov 22 01:59:57 volumio2 volumio[1014]: ---------------------------- MPD announces system playlist update Nov 22 01:59:57 volumio2 volumio[1014]: info: Ignoring MPD Status Update Nov 22 01:59:57 volumio2 volumio[1014]: info: Nov 22 01:59:57 volumio2 volumio[1014]: ---------------------------- MPD announces system playlist update Nov 22 01:59:57 volumio2 volumio[1014]: info: Ignoring MPD Status Update Nov 22 01:59:57 volumio2 volumio[1014]: info: ------------------------------ 11ms Nov 22 01:59:57 volumio2 volumio[1014]: info: sendMpdCommand play took 8 milliseconds Nov 22 01:59:57 volumio2 volumio[1014]: info: ------------------------------ 7ms Nov 22 01:59:57 volumio2 volumio[1014]: info: ------------------------------ 5ms Nov 22 01:59:57 volumio2 volumio[1014]: info: Nov 22 01:59:57 volumio2 volumio[1014]: ---------------------------- MPD announces state update: player Nov 22 01:59:57 volumio2 volumio[1014]: info: ControllerMpd::getState Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Nov 22 01:59:57 volumio2 volumio[1014]: info: Nov 22 01:59:57 volumio2 volumio[1014]: ---------------------------- MPD announces state update: player Nov 22 01:59:57 volumio2 volumio[1014]: info: ControllerMpd::getState Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Nov 22 01:59:57 volumio2 volumio[1014]: info: Nov 22 01:59:57 volumio2 volumio[1014]: ---------------------------- MPD announces state update: player Nov 22 01:59:57 volumio2 volumio[1014]: info: ControllerMpd::getState Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Nov 22 01:59:57 volumio2 volumio[1014]: info: sendMpdCommand status took 19 milliseconds Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::parseState Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 01:59:57 volumio2 volumio[1014]: info: Nov 22 01:59:57 volumio2 volumio[1014]: ---------------------------- MPD announces state update: player Nov 22 01:59:57 volumio2 volumio[1014]: info: ControllerMpd::getState Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Nov 22 01:59:57 volumio2 volumio[1014]: info: sendMpdCommand status took 26 milliseconds Nov 22 01:59:57 volumio2 volumio[1014]: info: sendMpdCommand status took 24 milliseconds Nov 22 01:59:57 volumio2 volumio[1014]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::parseState Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::parseState Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::parseTrackInfo Nov 22 01:59:57 volumio2 volumio[1014]: info: ControllerMpd::pushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreCommandRouter::servicePushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CorePlayQueue::getTrack 0 Nov 22 01:59:57 volumio2 volumio[1014]: 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":"","artist":null,"album":null,"uri":"http://live.dancemusic.ro:7000/","trackType":"ro:7000/"} Nov 22 01:59:57 volumio2 volumio[1014]: verbose: CURRENT POSITION 0 Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreStateMachine::syncState stateService play Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreStateMachine::syncState currentStatus stop Nov 22 01:59:57 volumio2 volumio[1014]: info: ------------------------------ 45ms Nov 22 01:59:57 volumio2 volumio[1014]: info: Nov 22 01:59:57 volumio2 volumio[1014]: ---------------------------- MPD announces state update: player Nov 22 01:59:57 volumio2 volumio[1014]: info: ControllerMpd::getState Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Nov 22 01:59:57 volumio2 volumio[1014]: info: Nov 22 01:59:57 volumio2 volumio[1014]: ---------------------------- MPD announces state update: player Nov 22 01:59:57 volumio2 volumio[1014]: info: ControllerMpd::getState Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Nov 22 01:59:57 volumio2 volumio[1014]: info: sendMpdCommand status took 20 milliseconds Nov 22 01:59:57 volumio2 volumio[1014]: info: sendMpdCommand playlistinfo took 16 milliseconds Nov 22 01:59:57 volumio2 volumio[1014]: info: sendMpdCommand playlistinfo took 16 milliseconds Nov 22 01:59:57 volumio2 volumio[1014]: info: sendMpdCommand status took 5 milliseconds Nov 22 01:59:57 volumio2 volumio[1014]: info: sendMpdCommand status took 4 milliseconds Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::parseState Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::parseTrackInfo Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::parseTrackInfo Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::parseState Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::parseState Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 01:59:57 volumio2 volumio[1014]: info: ControllerMpd::pushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreCommandRouter::servicePushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CorePlayQueue::getTrack 0 Nov 22 01:59:57 volumio2 volumio[1014]: 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":"","artist":null,"album":null,"uri":"http://live.dancemusic.ro:7000/","trackType":"ro:7000/"} Nov 22 01:59:57 volumio2 volumio[1014]: verbose: CURRENT POSITION 0 Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreStateMachine::syncState stateService play Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreStateMachine::syncState currentStatus play Nov 22 01:59:57 volumio2 volumio[1014]: info: Received an update from plugin. extracting info from payload Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreStateMachine::pushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreCommandRouter::volumioPushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreStateMachine::pushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreCommandRouter::volumioPushState Nov 22 01:59:57 volumio2 volumio[1014]: info: ControllerMpd::pushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreCommandRouter::servicePushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CorePlayQueue::getTrack 0 Nov 22 01:59:57 volumio2 volumio[1014]: 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":"","artist":null,"album":null,"uri":"http://live.dancemusic.ro:7000/","trackType":"ro:7000/"} Nov 22 01:59:57 volumio2 volumio[1014]: verbose: CURRENT POSITION 0 Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreStateMachine::syncState stateService play Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreStateMachine::syncState currentStatus play Nov 22 01:59:57 volumio2 volumio[1014]: info: Received an update from plugin. extracting info from payload Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreStateMachine::pushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreCommandRouter::volumioPushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreStateMachine::pushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreCommandRouter::volumioPushState Nov 22 01:59:57 volumio2 volumio[1014]: info: ------------------------------ 87ms Nov 22 01:59:57 volumio2 volumio[1014]: info: ------------------------------ 84ms Nov 22 01:59:57 volumio2 volumio[1014]: info: sendMpdCommand playlistinfo took 39 milliseconds Nov 22 01:59:57 volumio2 volumio[1014]: info: sendMpdCommand playlistinfo took 38 milliseconds Nov 22 01:59:57 volumio2 volumio[1014]: info: sendMpdCommand playlistinfo took 38 milliseconds Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::parseTrackInfo Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::parseTrackInfo Nov 22 01:59:57 volumio2 volumio[1014]: verbose: ControllerMpd::parseTrackInfo Nov 22 01:59:57 volumio2 volumio[1014]: info: ControllerMpd::pushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreCommandRouter::servicePushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CorePlayQueue::getTrack 0 Nov 22 01:59:57 volumio2 volumio[1014]: 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":"","artist":null,"album":null,"uri":"http://live.dancemusic.ro:7000/","trackType":"ro:7000/"} Nov 22 01:59:57 volumio2 volumio[1014]: verbose: CURRENT POSITION 0 Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreStateMachine::syncState stateService play Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreStateMachine::syncState currentStatus play Nov 22 01:59:57 volumio2 volumio[1014]: info: Received an update from plugin. extracting info from payload Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreStateMachine::pushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreCommandRouter::volumioPushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreStateMachine::pushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreCommandRouter::volumioPushState Nov 22 01:59:57 volumio2 volumio[1014]: info: ControllerMpd::pushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreCommandRouter::servicePushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CorePlayQueue::getTrack 0 Nov 22 01:59:57 volumio2 volumio[1014]: 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":"","artist":null,"album":null,"uri":"http://live.dancemusic.ro:7000/","trackType":"ro:7000/"} Nov 22 01:59:57 volumio2 volumio[1014]: verbose: CURRENT POSITION 0 Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreStateMachine::syncState stateService play Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreStateMachine::syncState currentStatus play Nov 22 01:59:57 volumio2 volumio[1014]: info: Received an update from plugin. extracting info from payload Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreStateMachine::pushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreCommandRouter::volumioPushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreStateMachine::pushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreCommandRouter::volumioPushState Nov 22 01:59:57 volumio2 volumio[1014]: info: ControllerMpd::pushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreCommandRouter::servicePushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CorePlayQueue::getTrack 0 Nov 22 01:59:57 volumio2 volumio[1014]: 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":"","artist":null,"album":null,"uri":"http://live.dancemusic.ro:7000/","trackType":"ro:7000/"} Nov 22 01:59:57 volumio2 volumio[1014]: verbose: CURRENT POSITION 0 Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreStateMachine::syncState stateService play Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreStateMachine::syncState currentStatus play Nov 22 01:59:57 volumio2 volumio[1014]: info: Received an update from plugin. extracting info from payload Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreStateMachine::pushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreCommandRouter::volumioPushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreStateMachine::pushState Nov 22 01:59:57 volumio2 volumio[1014]: info: CoreCommandRouter::volumioPushState Nov 22 01:59:57 volumio2 volumio[1014]: info: ------------------------------ 118ms Nov 22 01:59:57 volumio2 volumio[1014]: info: ------------------------------ 101ms Nov 22 01:59:57 volumio2 volumio[1014]: info: ------------------------------ 99ms Nov 22 01:59:57 volumio2 volumio[1014]: info: [ASDebug] CurState: play PrevState: stop Nov 22 01:59:57 volumio2 volumio[1014]: info: [ASDebug] Togle GPIO: ON Nov 22 01:59:57 volumio2 volumio[1014]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 22 01:59:57 volumio2 volumio[1014]: Error: EBADF: bad file descriptor, write Nov 22 01:59:57 volumio2 volumio[1014]: at Object.writeSync (fs.js:711:3) Nov 22 01:59:57 volumio2 volumio[1014]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Nov 22 01:59:57 volumio2 volumio[1014]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:209:23) Nov 22 01:59:57 volumio2 volumio[1014]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:190:86) Nov 22 01:59:57 volumio2 volumio[1014]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Nov 22 01:59:57 volumio2 volumio[1014]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10) Nov 22 01:59:57 volumio2 volumio[1014]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12) Nov 22 01:59:57 volumio2 volumio[1014]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Nov 22 01:59:57 volumio2 volumio[1014]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Nov 22 01:59:57 volumio2 volumio[1014]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8) Nov 22 01:59:57 volumio2 volumio[1014]: at Decoder. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Nov 22 01:59:57 volumio2 volumio[1014]: at Decoder.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Nov 22 01:59:57 volumio2 volumio[1014]: at Decoder.add (/data/plugins/system_controller/ampswitch/node_modules/socket.io-parser/index.js:254:12) Nov 22 01:59:57 volumio2 volumio[1014]: at Manager.ondata (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:339:16) Nov 22 01:59:57 volumio2 volumio[1014]: at Socket. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Nov 22 01:59:57 volumio2 volumio[1014]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) { Nov 22 01:59:57 volumio2 volumio[1014]: errno: -9, Nov 22 01:59:57 volumio2 volumio[1014]: syscall: 'write', Nov 22 01:59:57 volumio2 volumio[1014]: code: 'EBADF' Nov 22 01:59:57 volumio2 volumio[1014]: } Nov 22 01:59:57 volumio2 volumio[1014]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 22 01:59:58 volumio2 sudo[3653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-22 01:58 Nov 22 01:59:58 volumio2 sudo[3653]: 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"