-- Logs begin at Sat 2024-11-23 16:06:43 CET, end at Sat 2024-11-23 16:09:41 CET. -- Nov 23 16:08:00 musique-salon volumio[985]: error: MyVolumio Custom Token format not valid, refreshing it Nov 23 16:08:00 musique-salon volumio[985]: info: MyVolumio login type: Token Nov 23 16:08:00 musique-salon volumio[985]: STREAMING PROXY: Starting server on port 3245 Nov 23 16:08:00 musique-salon volumio[985]: Node JS runtime: 14 Nov 23 16:08:01 musique-salon volumio[985]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 23 16:08:01 musique-salon volumio[985]: info: MyVolumio token set successfully Nov 23 16:08:01 musique-salon volumio[985]: info: MYVOLUMIO: Adding device Nov 23 16:08:01 musique-salon volumio[985]: info: MYVOLUMIO: Evaluating Server Nov 23 16:08:02 musique-salon volumio[985]: info: MyVolumio status changed Nov 23 16:08:02 musique-salon volumio[985]: info: Streaming services startup Nov 23 16:08:02 musique-salon volumio[985]: info: Starting Streaming Daemon Nov 23 16:08:02 musique-salon volumio[985]: info: Removing browser output: myVolumio user plan is not superstar Nov 23 16:08:02 musique-salon volumio[985]: info: Removing audio output: Nov 23 16:08:02 musique-salon volumio[985]: info: Stoppping Tunnel 1 Nov 23 16:08:02 musique-salon sudo[1317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 23 16:08:02 musique-salon sudo[1317]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 16:08:02 musique-salon sudo[1320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 23 16:08:02 musique-salon sudo[1320]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 16:08:02 musique-salon sudo[1317]: pam_unix(sudo:session): session closed for user root Nov 23 16:08:02 musique-salon volumio[985]: error: Cannot start Volumio Streaming Daemon Nov 23 16:08:02 musique-salon volumio[985]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 23 16:08:02 musique-salon volumio[985]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 23 16:08:02 musique-salon sudo[1320]: pam_unix(sudo:session): session closed for user root Nov 23 16:08:02 musique-salon volumio[985]: info: Remote SSH Stopped Nov 23 16:08:02 musique-salon volumio[985]: info: Setting Geolocation for MyVolumio to eu4 Nov 23 16:08:02 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 16:08:02 musique-salon volumio[985]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 23 16:08:03 musique-salon volumio[985]: info: Updating MyVolumio device info Nov 23 16:08:03 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 16:08:04 musique-salon volumio[985]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 23 16:08:06 musique-salon volumio[985]: info: MYVOLUMIO: Adding device Nov 23 16:08:06 musique-salon volumio[985]: info: MYVOLUMIO: Evaluating Server Nov 23 16:08:07 musique-salon volumio[985]: info: Setting Geolocation for MyVolumio to eu6 Nov 23 16:08:07 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 16:08:07 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 16:08:07 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 16:08:08 musique-salon volumio[985]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 23 16:08:08 musique-salon volumio[985]: info: Updating MyVolumio device info Nov 23 16:08:08 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 16:08:08 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 16:08:08 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 16:08:09 musique-salon volumio[985]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 23 16:08:11 musique-salon systemd[1]: systemd-timedated.service: Succeeded. Nov 23 16:08:13 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 23 16:08:13 musique-salon volumio[985]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Nov 23 16:08:13 musique-salon volumio[985]: info: CoreCommandRouter::volumioGetState Nov 23 16:08:13 musique-salon volumio[985]: info: CorePlayQueue::getTrack 0 Nov 23 16:08:14 musique-salon volumiologrotate[553]: ls: cannot access '/var/log/samba/log.wb-MUSIQUE': No such file or directory Nov 23 16:08:14 musique-salon volumiologrotate[553]: ls: cannot access 'SALON': No such file or directory Nov 23 16:08:16 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 16:08:16 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 16:08:16 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 23 16:08:17 musique-salon volumio-remote-updater[581]: No test mode Nov 23 16:08:17 musique-salon volumio-remote-updater[581]: No alpha test mode Nov 23 16:08:17 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 23 16:08:17 musique-salon volumio[985]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 23 16:08:17 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 23 16:08:18 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:08:18 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:08:18 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 23 16:08:18 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 23 16:08:18 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 23 16:08:18 musique-salon volumio[985]: info: CoreCommandRouter::volumioGetBrowseSources Nov 23 16:08:18 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 16:08:19 musique-salon volumio[985]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 23 16:08:19 musique-salon volumio[985]: info: Completed starting MyVolumio Plugin Nov 23 16:08:19 musique-salon volumio[985]: [Metrics] CommandRouter: 41s 233.32ms Nov 23 16:08:19 musique-salon volumio[985]: info: CoreCommandRouter::volumiosetStartupVolume Nov 23 16:08:19 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 16:08:19 musique-salon volumio[985]: info: VolumeController:: Setting startup Volume 50 Nov 23 16:08:19 musique-salon volumio[985]: info: VolumeController::SetAlsaVolume50 Nov 23 16:08:19 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 16:08:19 musique-salon volumio[985]: info: CoreCommandRouter::Close All Modals sent Nov 23 16:08:19 musique-salon volumio[985]: info: CoreCommandRouter::Close All Modals sent Nov 23 16:08:19 musique-salon volumio[985]: info: CoreStateMachine::pushState Nov 23 16:08:19 musique-salon volumio[985]: info: CorePlayQueue::getTrack 0 Nov 23 16:08:19 musique-salon volumio[985]: info: CoreCommandRouter::volumioPushState Nov 23 16:08:19 musique-salon volumio[985]: info: [GPIO_Control] Status changed: stop Nov 23 16:08:20 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 23 16:08:20 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 23 16:08:20 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 23 16:08:23 musique-salon ntpd[721]: Soliciting pool server 151.80.168.4 Nov 23 16:08:24 musique-salon ntpd[721]: Soliciting pool server 54.38.242.85 Nov 23 16:08:24 musique-salon ntpd[721]: Soliciting pool server 162.159.200.1 Nov 23 16:08:25 musique-salon ntpd[721]: Soliciting pool server 79.143.250.33 Nov 23 16:08:25 musique-salon ntpd[721]: Soliciting pool server 51.255.95.80 Nov 23 16:08:25 musique-salon ntpd[721]: Soliciting pool server 129.250.35.250 Nov 23 16:08:26 musique-salon ntpd[721]: Soliciting pool server 51.255.141.76 Nov 23 16:08:26 musique-salon ntpd[721]: Soliciting pool server 37.59.63.125 Nov 23 16:08:26 musique-salon ntpd[721]: Soliciting pool server 95.179.212.126 Nov 23 16:08:26 musique-salon volumio[985]: verbose: New Socket.io Connection to 192.168.1.100 from 192.168.1.18 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/27.0 Chrome/125.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Nov 23 16:08:26 musique-salon volumio[985]: info: BOOT COMPLETED Nov 23 16:08:26 musique-salon volumio[985]: info: CoreCommandRouter::volumioGetVisibleSources Nov 23 16:08:26 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 16:08:26 musique-salon volumio[985]: info: CoreCommandRouter::volumioGetState Nov 23 16:08:26 musique-salon volumio[985]: info: CorePlayQueue::getTrack 0 Nov 23 16:08:26 musique-salon volumio[985]: info: CoreCommandRouter::volumioGetQueue Nov 23 16:08:26 musique-salon volumio[985]: info: CoreStateMachine::getQueue Nov 23 16:08:26 musique-salon volumio[985]: info: CorePlayQueue::getQueue Nov 23 16:08:26 musique-salon volumio[985]: info: Listing playlists Nov 23 16:08:26 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 23 16:08:27 musique-salon ntpd[721]: Soliciting pool server 5.39.80.51 Nov 23 16:08:27 musique-salon ntpd[721]: Soliciting pool server 51.195.104.188 Nov 23 16:08:27 musique-salon ntpd[721]: Soliciting pool server 185.254.100.25 Nov 23 16:08:27 musique-salon ntpd[721]: Soliciting pool server 193.52.136.2 Nov 23 16:08:28 musique-salon ntpd[721]: Soliciting pool server 51.15.191.239 Nov 23 16:08:28 musique-salon ntpd[721]: Soliciting pool server 2a12:8ac1:a0::123:3 Nov 23 16:08:28 musique-salon ntpd[721]: Soliciting pool server 54.36.61.42 Nov 23 16:08:28 musique-salon ntpd[721]: Soliciting pool server 51.38.38.92 Nov 23 16:08:29 musique-salon ntpd[721]: Soliciting pool server 82.64.230.205 Nov 23 16:08:29 musique-salon ntpd[721]: Soliciting pool server 82.64.100.180 Nov 23 16:08:33 musique-salon ntpd[721]: receive: Unexpected origin timestamp 0xeaec6e70.15e12b4e does not match aorg 0000000000.00000000 from server@193.52.136.2 xmt 0xeaec6e71.45cf417c Nov 23 16:08:56 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 23 16:08:56 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 23 16:08:59 musique-salon volumio[985]: info: Enabling plugin ampswitch Nov 23 16:08:59 musique-salon volumio[985]: info: Loading plugin "ampswitch"... Nov 23 16:09:00 musique-salon volumio[985]: info: PLUGIN START: ampswitch Nov 23 16:09:00 musique-salon volumio[985]: info: Done. Nov 23 16:09:00 musique-salon volumio[985]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Nov 23 16:09:00 musique-salon volumio[985]: info: CoreCommandRouter::volumioGetState Nov 23 16:09:00 musique-salon volumio[985]: info: CorePlayQueue::getTrack 0 Nov 23 16:09:00 musique-salon volumio[985]: info: [ASDebug] CurState: stop PrevState: na Nov 23 16:09:00 musique-salon volumio[985]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Nov 23 16:09:00 musique-salon volumio[985]: info: [ASDebug] CurState: stop PrevState: na Nov 23 16:09:00 musique-salon volumio[985]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Nov 23 16:09:01 musique-salon volumio[985]: info: [ASDebug] Togle GPIO: OFF Nov 23 16:09:06 musique-salon volumio[985]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 16:09:06 musique-salon volumio[985]: info: [ASDebug] Setting UI defaults Nov 23 16:09:06 musique-salon volumio[985]: info: [ASDebug] Port: 24 Nov 23 16:09:06 musique-salon volumio[985]: info: [ASDebug] Inverted: false Nov 23 16:09:06 musique-salon volumio[985]: info: [ASDebug] Latched: false Nov 23 16:09:06 musique-salon volumio[985]: info: [ASDebug] On pulse width: 500 Nov 23 16:09:06 musique-salon volumio[985]: info: [ASDebug] Off pulse width: 500 Nov 23 16:09:15 musique-salon volumiologrotate[553]: ls: cannot access '/var/log/samba/log.wb-MUSIQUE': No such file or directory Nov 23 16:09:15 musique-salon volumiologrotate[553]: ls: cannot access 'SALON': No such file or directory Nov 23 16:09:34 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 23 16:09:34 musique-salon volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 23 16:09:39 musique-salon volumio[985]: info: Starting Uninstall of plugin system_controller - ampswitch Nov 23 16:09:39 musique-salon volumio[985]: info: Uninstalling plugin ampswitch Nov 23 16:09:39 musique-salon volumio[985]: info: [ASDebug] Port: 24 Nov 23 16:09:39 musique-salon volumio[985]: info: [ASDebug] Inverted: false Nov 23 16:09:39 musique-salon volumio[985]: info: [ASDebug] Delay: 720 Nov 23 16:09:41 musique-salon volumio[985]: info: [ASDebug] Port: 24 Nov 23 16:09:41 musique-salon volumio[985]: info: [ASDebug] Inverted: false Nov 23 16:09:41 musique-salon volumio[985]: info: [ASDebug] Delay: 720 Nov 23 16:09:41 musique-salon volumio[985]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 16:09:41 musique-salon volumio[985]: Error: EBADF: bad file descriptor, close Nov 23 16:09:41 musique-salon volumio[985]: at Object.closeSync (fs.js:439:3) Nov 23 16:09:41 musique-salon volumio[985]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Nov 23 16:09:41 musique-salon volumio[985]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Nov 23 16:09:41 musique-salon volumio[985]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Nov 23 16:09:41 musique-salon volumio[985]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Nov 23 16:09:41 musique-salon volumio[985]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Nov 23 16:09:41 musique-salon volumio[985]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Nov 23 16:09:41 musique-salon volumio[985]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Nov 23 16:09:41 musique-salon volumio[985]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1551:29) Nov 23 16:09:41 musique-salon volumio[985]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1395:47) Nov 23 16:09:41 musique-salon volumio[985]: at Socket.emit (events.js:315:20) Nov 23 16:09:41 musique-salon volumio[985]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 23 16:09:41 musique-salon volumio[985]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Nov 23 16:09:41 musique-salon volumio[985]: errno: -9, Nov 23 16:09:41 musique-salon volumio[985]: syscall: 'close', Nov 23 16:09:41 musique-salon volumio[985]: code: 'EBADF' Nov 23 16:09:41 musique-salon volumio[985]: } Nov 23 16:09:41 musique-salon volumio[985]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 16:09:41 musique-salon sudo[1605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-23 16:08 Nov 23 16:09:41 musique-salon sudo[1605]: 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"