-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Fri 2024-09-06 09:24:09 UTC. -- Sep 06 09:23:23 volumio ntpd[675]: receive: Unexpected origin timestamp 0xe8789b31.668c6bff does not match aorg 0000000000.00000000 from server@178.239.19.60 xmt 0xea85488b.ae938b10 Sep 06 09:23:23 volumio systemd[1]: Starting Daily apt download activities... Sep 06 09:23:23 volumio ntpd[675]: receive: Unexpected origin timestamp 0xe8789b31.668717d4 does not match aorg 0000000000.00000000 from server@45.32.4.67 xmt 0xea85488b.b73d4339 Sep 06 09:23:24 volumio volumio[882]: info: CoreCommandRouter::volumioPlay Sep 06 09:23:24 volumio volumio[882]: info: CoreStateMachine::play index undefined Sep 06 09:23:24 volumio volumio[882]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 09:23:24 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 06 09:23:24 volumio volumio[882]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 06 09:23:24 volumio volumio[882]: info: Adding plugin bluetooth to MyMusic Plugins Sep 06 09:23:24 volumio volumio[882]: info: Adding plugin multiroom to MyMusic Plugins Sep 06 09:23:25 volumio volumio[882]: info: Adding plugin metavolumio to MyMusic Plugins Sep 06 09:23:25 volumio volumio[882]: info: Adding plugin cd_controller to MyMusic Plugins Sep 06 09:23:25 volumio volumio[882]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 06 09:23:25 volumio volumio[882]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 06 09:23:25 volumio volumio[882]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 06 09:23:26 volumio volumio[882]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 06 09:23:26 volumio volumio[882]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 06 09:23:26 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 09:23:26 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 09:23:26 volumio volumio[882]: info: Starting MyVolumio Remote Streaming Endpoints Sep 06 09:23:26 volumio volumio[882]: info: MyVolumio login type: Token Sep 06 09:23:26 volumio volumio[882]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 06 09:23:26 volumio volumio[882]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 06 09:23:27 volumio systemd[1]: apt-daily.service: Succeeded. Sep 06 09:23:27 volumio systemd[1]: Started Daily apt download activities. Sep 06 09:23:27 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Sep 06 09:23:28 volumio volumio[882]: info: Starting Streaming Service Transparent Proxy Sep 06 09:23:28 volumio volumio[882]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 06 09:23:28 volumio volumio[882]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 06 09:23:28 volumio volumio[882]: info: Streaming services startup Sep 06 09:23:28 volumio volumio[882]: info: Starting Streaming Daemon Sep 06 09:23:28 volumio volumio[882]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 06 09:23:29 volumio volumio[882]: info: Discovery: A device disappeared from network Sep 06 09:23:29 volumio volumio[882]: info: Discovery: Device volumio disappeared from network Sep 06 09:23:29 volumio volumio[882]: info: CoreCommandRouter::volumioGetState Sep 06 09:23:29 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Sep 06 09:23:29 volumio volumio[882]: info: CoreCommandRouter::volumioPlay Sep 06 09:23:29 volumio volumio[882]: info: CoreStateMachine::play index undefined Sep 06 09:23:29 volumio volumio[882]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 09:23:29 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Sep 06 09:23:29 volumio sudo[1159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 06 09:23:29 volumio sudo[1159]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 09:23:29 volumio sudo[1159]: pam_unix(sudo:session): session closed for user root Sep 06 09:23:29 volumio volumio[882]: error: Cannot start Volumio Streaming Daemon Sep 06 09:23:29 volumio volumio[882]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 06 09:23:29 volumio volumio[882]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 06 09:23:29 volumio volumio[882]: info: Discovery: adding ff532ae1-035c-4917-b881-81cb2f5385d5 Sep 06 09:23:29 volumio volumio[882]: info: Discovery: Found device Volumio Sep 06 09:23:29 volumio volumio[882]: info: CoreCommandRouter::volumioGetState Sep 06 09:23:29 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Sep 06 09:23:29 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Sep 06 09:23:29 volumio systemd[1]: Started Daily apt upgrade and clean activities. Sep 06 09:23:29 volumio volumio[882]: error: MyVolumio Custom Token format not valid, refreshing it Sep 06 09:23:30 volumio volumio[882]: STREAMING PROXY: Starting server on port 3245 Sep 06 09:23:30 volumio volumio[882]: Node JS runtime: 14 Sep 06 09:23:30 volumio volumio[882]: info: MyVolumio login type: Token Sep 06 09:23:32 volumio volumio[882]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 06 09:23:32 volumio volumio[882]: info: MYVOLUMIO: Adding device Sep 06 09:23:32 volumio volumio[882]: info: MYVOLUMIO: Evaluating Server Sep 06 09:23:32 volumio volumio[882]: info: MyVolumio status changed Sep 06 09:23:32 volumio volumio[882]: info: Streaming services startup Sep 06 09:23:32 volumio volumio[882]: info: Starting Streaming Daemon Sep 06 09:23:32 volumio volumio[882]: info: Removing browser output: myVolumio user plan is not superstar Sep 06 09:23:32 volumio volumio[882]: info: Removing audio output: Sep 06 09:23:32 volumio volumio[882]: info: Stoppping Tunnel 1 Sep 06 09:23:32 volumio sudo[1215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 06 09:23:32 volumio sudo[1215]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 09:23:32 volumio sudo[1218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 06 09:23:32 volumio sudo[1215]: pam_unix(sudo:session): session closed for user root Sep 06 09:23:32 volumio sudo[1218]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 09:23:32 volumio sudo[1218]: pam_unix(sudo:session): session closed for user root Sep 06 09:23:32 volumio volumio[882]: error: Cannot start Volumio Streaming Daemon Sep 06 09:23:32 volumio volumio[882]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 06 09:23:32 volumio volumio[882]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 06 09:23:32 volumio volumio[882]: info: Remote SSH Stopped Sep 06 09:23:32 volumio volumio[882]: info: Setting Geolocation for MyVolumio to eu8 Sep 06 09:23:32 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 09:23:32 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 09:23:32 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 09:23:33 volumio volumio[882]: info: Updating MyVolumio device info Sep 06 09:23:33 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 09:23:33 volumio volumio[882]: info: CoreCommandRouter::volumioPlay Sep 06 09:23:33 volumio volumio[882]: info: CoreStateMachine::play index undefined Sep 06 09:23:33 volumio volumio[882]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 09:23:33 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Sep 06 09:23:34 volumio volumio[882]: info: Successfully Updated MyVolumio device Sep 06 09:23:34 volumio volumio[882]: info: Successfully Added MyVolumio device Sep 06 09:23:35 volumio volumio[882]: info: CoreCommandRouter::volumioGetState Sep 06 09:23:35 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Sep 06 09:23:35 volumio volumio[882]: info: VolumeController::SetAlsaVolume- Sep 06 09:23:35 volumio volumio[882]: info: CoreStateMachine::pushState Sep 06 09:23:35 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Sep 06 09:23:35 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 09:23:35 volumio volumio[882]: info: CoreCommandRouter::volumioPushState Sep 06 09:23:35 volumio volumio[882]: info: [ASDebug] CurState: stop PrevState: stop Sep 06 09:23:35 volumio volumio[882]: info: Listing playlists Sep 06 09:23:35 volumio volumio[882]: info: Listing playlists Sep 06 09:23:37 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 06 09:23:37 volumio volumio[882]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 06 09:23:37 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 06 09:23:37 volumio volumio[882]: info: Received Get System Version Sep 06 09:23:37 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 06 09:23:37 volumio volumio[882]: info: Received Get System Info Sep 06 09:23:37 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 09:23:37 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 09:23:37 volumio volumio[882]: info: Discovery: Getting this device information Sep 06 09:23:37 volumio volumio[882]: info: CoreCommandRouter::volumioGetState Sep 06 09:23:37 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Sep 06 09:23:37 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 09:23:41 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 06 09:23:41 volumio volumio[882]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Sep 06 09:23:41 volumio volumio[882]: info: CoreCommandRouter::volumioGetState Sep 06 09:23:41 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Sep 06 09:23:42 volumio volumio[882]: info: CoreCommandRouter::volumioPlay Sep 06 09:23:42 volumio volumio[882]: info: CoreStateMachine::play index undefined Sep 06 09:23:42 volumio volumio[882]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 09:23:42 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Sep 06 09:23:45 volumio volumio[882]: info: CoreCommandRouter::volumioGetState Sep 06 09:23:45 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Sep 06 09:23:46 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 09:23:46 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 09:23:46 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 06 09:23:46 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 06 09:23:46 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 06 09:23:46 volumio volumio[882]: info: CoreCommandRouter::volumioGetBrowseSources Sep 06 09:23:46 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 06 09:23:50 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 06 09:23:50 volumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 06 09:23:55 volumio volumio[882]: info: CoreCommandRouter::volumioGetState Sep 06 09:23:55 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Sep 06 09:23:55 volumio volumio[882]: info: Listing playlists Sep 06 09:23:55 volumio volumio[882]: info: Listing playlists Sep 06 09:24:05 volumio volumio[882]: info: CoreCommandRouter::volumioGetState Sep 06 09:24:05 volumio volumio[882]: info: CorePlayQueue::getTrack 0 Sep 06 09:24:06 volumio volumio[882]: info: [ASDebug] Port: 14 Sep 06 09:24:06 volumio volumio[882]: info: [ASDebug] Inverted: false Sep 06 09:24:06 volumio volumio[882]: info: [ASDebug] Delay: 2500 Sep 06 09:24:08 volumio volumio[882]: info: [ASDebug] Port: 14 Sep 06 09:24:08 volumio volumio[882]: info: [ASDebug] Inverted: false Sep 06 09:24:08 volumio volumio[882]: info: [ASDebug] Delay: 2500 Sep 06 09:24:08 volumio volumio[882]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 09:24:08 volumio volumio[882]: Error: EBADF: bad file descriptor, close Sep 06 09:24:08 volumio volumio[882]: at Object.closeSync (fs.js:439:3) Sep 06 09:24:08 volumio volumio[882]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Sep 06 09:24:08 volumio volumio[882]: at Object.closeSync (/data/plugins/miscellanea/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Sep 06 09:24:08 volumio volumio[882]: at Gpio.unexport (/data/plugins/miscellanea/ampswitch/node_modules/onoff/onoff.js:307:8) Sep 06 09:24:08 volumio volumio[882]: at AmpSwitchController.freeGPIO (/data/plugins/miscellanea/ampswitch/index.js:230:19) Sep 06 09:24:08 volumio volumio[882]: at AmpSwitchController.onStop (/data/plugins/miscellanea/ampswitch/index.js:84:10) Sep 06 09:24:08 volumio volumio[882]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Sep 06 09:24:08 volumio volumio[882]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Sep 06 09:24:08 volumio volumio[882]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1525:29) Sep 06 09:24:08 volumio volumio[882]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1356:47) Sep 06 09:24:08 volumio volumio[882]: at Socket.emit (events.js:315:20) Sep 06 09:24:08 volumio volumio[882]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Sep 06 09:24:08 volumio volumio[882]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Sep 06 09:24:08 volumio volumio[882]: errno: -9, Sep 06 09:24:08 volumio volumio[882]: syscall: 'close', Sep 06 09:24:08 volumio volumio[882]: code: 'EBADF' Sep 06 09:24:08 volumio volumio[882]: } Sep 06 09:24:08 volumio volumio[882]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 09:24:09 volumio sudo[1234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-06 09:23 Sep 06 09:24:09 volumio sudo[1234]: 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="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62"