-- Logs begin at Thu 2019-02-14 12:12:00 EET, end at Mon 2024-12-02 21:18:52 EET. -- Dec 02 21:17:30 volumio2 sudo[2524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 02 21:17:30 volumio2 sudo[2524]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:17:30 volumio2 sudo[2524]: pam_unix(sudo:session): session closed for user root Dec 02 21:17:30 volumio2 sudo[2527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 02 21:17:30 volumio2 sudo[2527]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 21:17:30 volumio2 sudo[2527]: pam_unix(sudo:session): session closed for user root Dec 02 21:17:30 volumio2 volumio[1000]: 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 Dec 02 21:17:31 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 02 21:17:31 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 02 21:17:31 volumio2 volumio[1000]: info: CoreCommandRouter::volumioGetVisibleSources Dec 02 21:17:31 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 21:17:31 volumio2 volumio[1000]: info: CoreCommandRouter::volumioGetState Dec 02 21:17:31 volumio2 volumio[1000]: info: CorePlayQueue::getTrack 0 Dec 02 21:17:31 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 02 21:17:31 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 02 21:17:31 volumio2 volumio[1000]: info: Received Get System Info Dec 02 21:17:31 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 21:17:31 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 21:17:31 volumio2 volumio[1000]: info: Discovery: Getting this device information Dec 02 21:17:31 volumio2 volumio[1000]: info: CoreCommandRouter::volumioGetState Dec 02 21:17:31 volumio2 volumio[1000]: info: CorePlayQueue::getTrack 0 Dec 02 21:17:31 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 21:17:31 volumio2 volumio[1000]: info: CoreCommandRouter::volumioGetState Dec 02 21:17:31 volumio2 volumio[1000]: info: CorePlayQueue::getTrack 0 Dec 02 21:17:31 volumio2 volumio[1000]: info: Listing playlists Dec 02 21:17:31 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 02 21:17:31 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 02 21:17:31 volumio2 volumio[1000]: info: CoreCommandRouter::volumioGetState Dec 02 21:17:31 volumio2 volumio[1000]: info: CorePlayQueue::getTrack 0 Dec 02 21:17:32 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 02 21:17:32 volumio2 volumio[1000]: info: Received Get System Info Dec 02 21:17:32 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 21:17:32 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 21:17:32 volumio2 volumio[1000]: info: Discovery: Getting this device information Dec 02 21:17:32 volumio2 volumio[1000]: info: CoreCommandRouter::volumioGetState Dec 02 21:17:32 volumio2 volumio[1000]: info: CorePlayQueue::getTrack 0 Dec 02 21:17:32 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 21:17:33 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 02 21:17:34 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 02 21:17:34 volumio2 volumio[1000]: info: Received Get System Info Dec 02 21:17:34 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 21:17:34 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 21:17:34 volumio2 volumio[1000]: info: Discovery: Getting this device information Dec 02 21:17:34 volumio2 volumio[1000]: info: CoreCommandRouter::volumioGetState Dec 02 21:17:34 volumio2 volumio[1000]: info: CorePlayQueue::getTrack 0 Dec 02 21:17:34 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 21:17:35 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 02 21:17:35 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 02 21:17:40 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 02 21:17:48 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 02 21:17:48 volumio2 volumio[1000]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 02 21:17:48 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 02 21:17:48 volumio2 volumio[1000]: info: Received Get System Version Dec 02 21:17:48 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 02 21:17:48 volumio2 volumio[1000]: info: Received Get System Info Dec 02 21:17:48 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 21:17:48 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 21:17:48 volumio2 volumio[1000]: info: Discovery: Getting this device information Dec 02 21:17:48 volumio2 volumio[1000]: info: CoreCommandRouter::volumioGetState Dec 02 21:17:48 volumio2 volumio[1000]: info: CorePlayQueue::getTrack 0 Dec 02 21:17:48 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 21:17:57 volumio2 volumio[1000]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:17:57 volumio2 volumio[1000]: info: [youtube2] InnertubeLoader: creating Innertube instance... Dec 02 21:18:04 volumio2 volumio[1000]: info: [youtube2] InnertubeLoader: creating Auth instance... Dec 02 21:18:05 volumio2 volumio[1000]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:18:05 volumio2 volumio[1000]: info: [youtube2] Auth success Dec 02 21:18:05 volumio2 volumio[1000]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:18:05 volumio2 volumio[1000]: info: [youtube2] InnertubeLoader: Auth instance created (status: signed in) Dec 02 21:18:05 volumio2 volumio[1000]: 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 Dec 02 21:18:05 volumio2 volumio[1000]: at HTTPClient. (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:9501:13) Dec 02 21:18:05 volumio2 volumio[1000]: at Generator.next () Dec 02 21:18:05 volumio2 volumio[1000]: at fulfilled (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:215:30) Dec 02 21:18:05 volumio2 volumio[1000]: at runMicrotasks () Dec 02 21:18:05 volumio2 volumio[1000]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 02 21:18:05 volumio2 volumio[1000]: (node:1000) UnhandledPromiseRejectionWarning: TypeError: Cannot convert undefined or null to object Dec 02 21:18:05 volumio2 volumio[1000]: at Function.entries () Dec 02 21:18:05 volumio2 volumio[1000]: at findInObject (/data/plugins/music_service/youtube2/dist/lib/util/index.js:27:43) Dec 02 21:18:05 volumio2 volumio[1000]: at ConfigModel.getI18nOptions (/data/plugins/music_service/youtube2/dist/lib/model/ConfigModel.js:84:54) Dec 02 21:18:05 volumio2 volumio[1000]: at runMicrotasks () Dec 02 21:18:05 volumio2 volumio[1000]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 02 21:18:05 volumio2 volumio[1000]: (Use `node --trace-warnings ...` to show where the warning was created) Dec 02 21:18:05 volumio2 volumio[1000]: (node:1000) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1) Dec 02 21:18:05 volumio2 volumio[1000]: (node:1000) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Dec 02 21:18:05 volumio2 volumio[1000]: 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 Dec 02 21:18:05 volumio2 volumio[1000]: 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 Dec 02 21:18:05 volumio2 volumio[1000]: 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 Dec 02 21:18:05 volumio2 volumio[1000]: at HTTPClient. (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:9501:13) Dec 02 21:18:05 volumio2 volumio[1000]: at Generator.next () Dec 02 21:18:05 volumio2 volumio[1000]: at fulfilled (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:215:30) Dec 02 21:18:05 volumio2 volumio[1000]: at runMicrotasks () Dec 02 21:18:05 volumio2 volumio[1000]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 02 21:18:05 volumio2 volumio[1000]: (node:1000) UnhandledPromiseRejectionWarning: TypeError: Cannot convert undefined or null to object Dec 02 21:18:05 volumio2 volumio[1000]: at Function.entries () Dec 02 21:18:05 volumio2 volumio[1000]: at findInObject (/data/plugins/music_service/youtube2/dist/lib/util/index.js:27:43) Dec 02 21:18:05 volumio2 volumio[1000]: at ConfigModel.getI18nOptions (/data/plugins/music_service/youtube2/dist/lib/model/ConfigModel.js:84:54) Dec 02 21:18:05 volumio2 volumio[1000]: at runMicrotasks () Dec 02 21:18:05 volumio2 volumio[1000]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 02 21:18:05 volumio2 volumio[1000]: (node:1000) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 2) Dec 02 21:18:05 volumio2 volumio[1000]: 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 Dec 02 21:18:05 volumio2 volumio[1000]: at HTTPClient. (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:9501:13) Dec 02 21:18:05 volumio2 volumio[1000]: at Generator.next () Dec 02 21:18:05 volumio2 volumio[1000]: at fulfilled (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:215:30) Dec 02 21:18:05 volumio2 volumio[1000]: at runMicrotasks () Dec 02 21:18:05 volumio2 volumio[1000]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 02 21:18:05 volumio2 volumio[1000]: (node:1000) UnhandledPromiseRejectionWarning: TypeError: Cannot convert undefined or null to object Dec 02 21:18:05 volumio2 volumio[1000]: at Function.entries () Dec 02 21:18:05 volumio2 volumio[1000]: at findInObject (/data/plugins/music_service/youtube2/dist/lib/util/index.js:27:43) Dec 02 21:18:05 volumio2 volumio[1000]: at ConfigModel.getI18nOptions (/data/plugins/music_service/youtube2/dist/lib/model/ConfigModel.js:84:54) Dec 02 21:18:05 volumio2 volumio[1000]: at runMicrotasks () Dec 02 21:18:05 volumio2 volumio[1000]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 02 21:18:05 volumio2 volumio[1000]: (node:1000) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 3) Dec 02 21:18:05 volumio2 volumio[1000]: 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 Dec 02 21:18:05 volumio2 volumio[1000]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 02 21:18:31 volumio2 volumio[1000]: info: CoreCommandRouter::volumioGetState Dec 02 21:18:31 volumio2 volumio[1000]: info: CorePlayQueue::getTrack 0 Dec 02 21:18:36 volumio2 volumio[1000]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 21:18:36 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 02 21:18:37 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 02 21:18:37 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 02 21:18:37 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Dec 02 21:18:37 volumio2 volumio[1000]: info: Received Get System Version Dec 02 21:18:37 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 02 21:18:44 volumio2 volumio-remote-updater[663]: No test mode Dec 02 21:18:44 volumio2 volumio-remote-updater[663]: No alpha test mode Dec 02 21:18:45 volumio2 volumio[1000]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 02 21:18:45 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 02 21:18:46 volumio2 volumio[1000]: info: CoreCommandRouter::Close All Modals sent Dec 02 21:18:49 volumio2 volumio[1000]: info: CoreCommandRouter::volumioGetState Dec 02 21:18:49 volumio2 volumio[1000]: info: CorePlayQueue::getTrack 0 Dec 02 21:18:50 volumio2 volumio[1000]: info: CoreCommandRouter::volumioPlay Dec 02 21:18:50 volumio2 volumio[1000]: info: CoreStateMachine::play index undefined Dec 02 21:18:50 volumio2 volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 21:18:50 volumio2 volumio[1000]: info: CorePlayQueue::getTrack 0 Dec 02 21:18:50 volumio2 volumio[1000]: info: CoreStateMachine::startPlaybackTimer Dec 02 21:18:50 volumio2 volumio[1000]: info: CorePlayQueue::getTrack 0 Dec 02 21:18:50 volumio2 volumio[1000]: info: [1733167130953] ControllerWebradio::clearAddPlayTrack Dec 02 21:18:50 volumio2 volumio[1000]: verbose: ControllerMpd::sendMpdCommand stop Dec 02 21:18:50 volumio2 volumio[1000]: info: sendMpdCommand stop took 5 milliseconds Dec 02 21:18:50 volumio2 volumio[1000]: verbose: ControllerMpd::sendMpdCommand clear Dec 02 21:18:50 volumio2 volumio[1000]: info: Dec 02 21:18:50 volumio2 volumio[1000]: ---------------------------- MPD announces system playlist update Dec 02 21:18:50 volumio2 volumio[1000]: info: Ignoring MPD Status Update Dec 02 21:18:50 volumio2 volumio[1000]: info: sendMpdCommand clear took 4 milliseconds Dec 02 21:18:50 volumio2 volumio[1000]: verbose: ControllerMpd::sendMpdCommand load "http://live.dancemusic.ro:7000/" Dec 02 21:18:50 volumio2 volumio[1000]: info: Dec 02 21:18:50 volumio2 volumio[1000]: ---------------------------- MPD announces system playlist update Dec 02 21:18:50 volumio2 volumio[1000]: info: Ignoring MPD Status Update Dec 02 21:18:50 volumio2 volumio[1000]: info: Dec 02 21:18:50 volumio2 volumio[1000]: ---------------------------- MPD announces system playlist update Dec 02 21:18:50 volumio2 volumio[1000]: info: Ignoring MPD Status Update Dec 02 21:18:50 volumio2 volumio[1000]: error: updateQueue error: null Dec 02 21:18:50 volumio2 volumio[1000]: info: ------------------------------ 18ms Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::sendMpdCommand add "http://live.dancemusic.ro:7000/" Dec 02 21:18:51 volumio2 volumio[1000]: error: updateQueue error: null Dec 02 21:18:51 volumio2 volumio[1000]: error: updateQueue error: null Dec 02 21:18:51 volumio2 volumio[1000]: info: Dec 02 21:18:51 volumio2 volumio[1000]: ---------------------------- MPD announces system playlist update Dec 02 21:18:51 volumio2 volumio[1000]: info: Ignoring MPD Status Update Dec 02 21:18:51 volumio2 volumio[1000]: info: ------------------------------ 536ms Dec 02 21:18:51 volumio2 volumio[1000]: info: ------------------------------ 530ms Dec 02 21:18:51 volumio2 volumio[1000]: info: sendMpdCommand add "http://live.dancemusic.ro:7000/" took 4 milliseconds Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::sendMpdCommand play Dec 02 21:18:51 volumio2 volumio[1000]: info: Dec 02 21:18:51 volumio2 volumio[1000]: ---------------------------- MPD announces system playlist update Dec 02 21:18:51 volumio2 volumio[1000]: info: Ignoring MPD Status Update Dec 02 21:18:51 volumio2 volumio[1000]: info: Dec 02 21:18:51 volumio2 volumio[1000]: ---------------------------- MPD announces system playlist update Dec 02 21:18:51 volumio2 volumio[1000]: info: Ignoring MPD Status Update Dec 02 21:18:51 volumio2 volumio[1000]: info: ------------------------------ 12ms Dec 02 21:18:51 volumio2 volumio[1000]: info: sendMpdCommand play took 10 milliseconds Dec 02 21:18:51 volumio2 volumio[1000]: info: ------------------------------ 9ms Dec 02 21:18:51 volumio2 volumio[1000]: info: ------------------------------ 5ms Dec 02 21:18:51 volumio2 volumio[1000]: info: Dec 02 21:18:51 volumio2 volumio[1000]: ---------------------------- MPD announces state update: player Dec 02 21:18:51 volumio2 volumio[1000]: info: ControllerMpd::getState Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:18:51 volumio2 volumio[1000]: info: Dec 02 21:18:51 volumio2 volumio[1000]: ---------------------------- MPD announces state update: player Dec 02 21:18:51 volumio2 volumio[1000]: info: ControllerMpd::getState Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:18:51 volumio2 volumio[1000]: info: Dec 02 21:18:51 volumio2 volumio[1000]: ---------------------------- MPD announces state update: player Dec 02 21:18:51 volumio2 volumio[1000]: info: ControllerMpd::getState Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:18:51 volumio2 volumio[1000]: info: sendMpdCommand status took 21 milliseconds Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::parseState Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:18:51 volumio2 volumio[1000]: info: Dec 02 21:18:51 volumio2 volumio[1000]: ---------------------------- MPD announces state update: player Dec 02 21:18:51 volumio2 volumio[1000]: info: ControllerMpd::getState Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:18:51 volumio2 volumio[1000]: info: sendMpdCommand status took 29 milliseconds Dec 02 21:18:51 volumio2 volumio[1000]: info: sendMpdCommand status took 25 milliseconds Dec 02 21:18:51 volumio2 volumio[1000]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::parseState Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::parseState Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:18:51 volumio2 volumio[1000]: info: ControllerMpd::pushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreCommandRouter::servicePushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CorePlayQueue::getTrack 0 Dec 02 21:18:51 volumio2 volumio[1000]: 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/"} Dec 02 21:18:51 volumio2 volumio[1000]: verbose: CURRENT POSITION 0 Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreStateMachine::syncState stateService play Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreStateMachine::syncState currentStatus stop Dec 02 21:18:51 volumio2 volumio[1000]: info: ------------------------------ 50ms Dec 02 21:18:51 volumio2 volumio[1000]: info: Dec 02 21:18:51 volumio2 volumio[1000]: ---------------------------- MPD announces state update: player Dec 02 21:18:51 volumio2 volumio[1000]: info: ControllerMpd::getState Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:18:51 volumio2 volumio[1000]: info: Dec 02 21:18:51 volumio2 volumio[1000]: ---------------------------- MPD announces state update: player Dec 02 21:18:51 volumio2 volumio[1000]: info: ControllerMpd::getState Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:18:51 volumio2 volumio[1000]: info: sendMpdCommand status took 33 milliseconds Dec 02 21:18:51 volumio2 volumio[1000]: info: sendMpdCommand playlistinfo took 29 milliseconds Dec 02 21:18:51 volumio2 volumio[1000]: info: sendMpdCommand playlistinfo took 29 milliseconds Dec 02 21:18:51 volumio2 volumio[1000]: info: sendMpdCommand status took 16 milliseconds Dec 02 21:18:51 volumio2 volumio[1000]: info: sendMpdCommand status took 5 milliseconds Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::parseState Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::parseState Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::parseState Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:18:51 volumio2 volumio[1000]: info: ControllerMpd::pushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreCommandRouter::servicePushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CorePlayQueue::getTrack 0 Dec 02 21:18:51 volumio2 volumio[1000]: 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/"} Dec 02 21:18:51 volumio2 volumio[1000]: verbose: CURRENT POSITION 0 Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreStateMachine::syncState stateService play Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreStateMachine::syncState currentStatus play Dec 02 21:18:51 volumio2 volumio[1000]: info: Received an update from plugin. extracting info from payload Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreStateMachine::pushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreCommandRouter::volumioPushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreStateMachine::pushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreCommandRouter::volumioPushState Dec 02 21:18:51 volumio2 volumio[1000]: info: ControllerMpd::pushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreCommandRouter::servicePushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CorePlayQueue::getTrack 0 Dec 02 21:18:51 volumio2 volumio[1000]: 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/"} Dec 02 21:18:51 volumio2 volumio[1000]: verbose: CURRENT POSITION 0 Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreStateMachine::syncState stateService play Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreStateMachine::syncState currentStatus play Dec 02 21:18:51 volumio2 volumio[1000]: info: Received an update from plugin. extracting info from payload Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreStateMachine::pushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreCommandRouter::volumioPushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreStateMachine::pushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreCommandRouter::volumioPushState Dec 02 21:18:51 volumio2 volumio[1000]: info: ------------------------------ 132ms Dec 02 21:18:51 volumio2 volumio[1000]: info: ------------------------------ 130ms Dec 02 21:18:51 volumio2 volumio[1000]: info: Dec 02 21:18:51 volumio2 volumio[1000]: ---------------------------- MPD announces system playlist update Dec 02 21:18:51 volumio2 volumio[1000]: info: Ignoring MPD Status Update Dec 02 21:18:51 volumio2 volumio[1000]: info: Dec 02 21:18:51 volumio2 volumio[1000]: ---------------------------- MPD announces state update: player Dec 02 21:18:51 volumio2 volumio[1000]: info: sendMpdCommand playlistinfo took 76 milliseconds Dec 02 21:18:51 volumio2 volumio[1000]: info: ControllerMpd::getState Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:18:51 volumio2 volumio[1000]: info: sendMpdCommand playlistinfo took 75 milliseconds Dec 02 21:18:51 volumio2 volumio[1000]: info: sendMpdCommand playlistinfo took 75 milliseconds Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:18:51 volumio2 volumio[1000]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:18:51 volumio2 volumio[1000]: info: ControllerMpd::pushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreCommandRouter::servicePushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CorePlayQueue::getTrack 0 Dec 02 21:18:51 volumio2 volumio[1000]: 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/"} Dec 02 21:18:51 volumio2 volumio[1000]: verbose: CURRENT POSITION 0 Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreStateMachine::syncState stateService play Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreStateMachine::syncState currentStatus play Dec 02 21:18:51 volumio2 volumio[1000]: info: Received an update from plugin. extracting info from payload Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreStateMachine::pushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreCommandRouter::volumioPushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreStateMachine::pushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreCommandRouter::volumioPushState Dec 02 21:18:51 volumio2 volumio[1000]: info: ControllerMpd::pushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreCommandRouter::servicePushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CorePlayQueue::getTrack 0 Dec 02 21:18:51 volumio2 volumio[1000]: 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":"Faraon - Clarity (Original Mix)","artist":"Deep House Radio - Bucharest - Romania -","album":null,"uri":"http://live.dancemusic.ro:7000/","trackType":"ro:7000/"} Dec 02 21:18:51 volumio2 volumio[1000]: verbose: CURRENT POSITION 0 Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreStateMachine::syncState stateService play Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreStateMachine::syncState currentStatus play Dec 02 21:18:51 volumio2 volumio[1000]: info: Received an update from plugin. extracting info from payload Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreStateMachine::pushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreCommandRouter::volumioPushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreStateMachine::pushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreCommandRouter::volumioPushState Dec 02 21:18:51 volumio2 volumio[1000]: info: ControllerMpd::pushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreCommandRouter::servicePushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CorePlayQueue::getTrack 0 Dec 02 21:18:51 volumio2 volumio[1000]: 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":"Faraon - Clarity (Original Mix)","artist":"Deep House Radio - Bucharest - Romania -","album":null,"uri":"http://live.dancemusic.ro:7000/","trackType":"ro:7000/"} Dec 02 21:18:51 volumio2 volumio[1000]: verbose: CURRENT POSITION 0 Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreStateMachine::syncState stateService play Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreStateMachine::syncState currentStatus play Dec 02 21:18:51 volumio2 volumio[1000]: info: Received an update from plugin. extracting info from payload Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreStateMachine::pushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreCommandRouter::volumioPushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreStateMachine::pushState Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreCommandRouter::volumioPushState Dec 02 21:18:51 volumio2 volumio[1000]: info: ------------------------------ 210ms Dec 02 21:18:51 volumio2 volumio[1000]: info: ------------------------------ 193ms Dec 02 21:18:51 volumio2 volumio[1000]: info: ------------------------------ 182ms Dec 02 21:18:51 volumio2 volumio[1000]: info: CoreCommandRouter::volumioGetState Dec 02 21:18:51 volumio2 volumio[1000]: info: [ASDebug] CurState: play PrevState: stop Dec 02 21:18:51 volumio2 volumio[1000]: info: [ASDebug] Pulsing GPIO for 500ms Dec 02 21:18:51 volumio2 volumio[1000]: info: [ASDebug] Togle GPIO: ON Dec 02 21:18:51 volumio2 volumio[1000]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 21:18:51 volumio2 volumio[1000]: Error: ESPIPE: invalid seek, write Dec 02 21:18:51 volumio2 volumio[1000]: at Object.writeSync (fs.js:711:3) Dec 02 21:18:51 volumio2 volumio[1000]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Dec 02 21:18:51 volumio2 volumio[1000]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:207:23) Dec 02 21:18:51 volumio2 volumio[1000]: at AmpSwitchController.pulse (/data/plugins/system_controller/ampswitch/index.js:231:8) Dec 02 21:18:51 volumio2 volumio[1000]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:190:38) Dec 02 21:18:51 volumio2 volumio[1000]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Dec 02 21:18:51 volumio2 volumio[1000]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10) Dec 02 21:18:51 volumio2 volumio[1000]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12) Dec 02 21:18:51 volumio2 volumio[1000]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Dec 02 21:18:51 volumio2 volumio[1000]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Dec 02 21:18:51 volumio2 volumio[1000]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8) Dec 02 21:18:51 volumio2 volumio[1000]: at Decoder. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Dec 02 21:18:51 volumio2 volumio[1000]: at Decoder.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Dec 02 21:18:51 volumio2 volumio[1000]: at Decoder.add (/data/plugins/system_controller/ampswitch/node_modules/socket.io-parser/index.js:254:12) Dec 02 21:18:51 volumio2 volumio[1000]: at Manager.ondata (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:339:16) Dec 02 21:18:51 volumio2 volumio[1000]: at Socket. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) { Dec 02 21:18:51 volumio2 volumio[1000]: errno: -29, Dec 02 21:18:51 volumio2 volumio[1000]: syscall: 'write', Dec 02 21:18:51 volumio2 volumio[1000]: code: 'ESPIPE' Dec 02 21:18:51 volumio2 volumio[1000]: } Dec 02 21:18:51 volumio2 volumio[1000]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 21:18:52 volumio2 sudo[2752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-02 21:17 Dec 02 21:18:52 volumio2 sudo[2752]: 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"