-- Logs begin at Wed 2024-10-09 09:13:23 CEST, end at Wed 2024-10-09 09:18:41 CEST. -- Oct 09 09:17:01 music CRON[1321]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 09 09:17:01 music CRON[1322]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) Oct 09 09:17:01 music CRON[1321]: pam_unix(cron:session): session closed for user root Oct 09 09:17:02 music volumio[1014]: info: MyVolumio token set successfully Oct 09 09:17:02 music volumio[1014]: info: MYVOLUMIO: Adding device Oct 09 09:17:02 music volumio[1014]: info: MYVOLUMIO: Evaluating Server Oct 09 09:17:06 music volumio[1014]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 09 09:17:06 music volumio[1014]: info: Completed starting MyVolumio Plugin Oct 09 09:17:06 music volumio[1014]: info: BOOT COMPLETED Oct 09 09:17:06 music volumio[1014]: [Metrics] CommandRouter: 108s 476.97ms Oct 09 09:17:06 music volumio[1014]: info: CoreCommandRouter::volumiosetStartupVolume Oct 09 09:17:06 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 09 09:17:06 music volumio[1014]: info: VolumeController:: Setting startup Volume 35 Oct 09 09:17:06 music volumio[1014]: info: VolumeController::SetAlsaVolume35 Oct 09 09:17:07 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 09 09:17:07 music volumio[1014]: info: CoreCommandRouter::Close All Modals sent Oct 09 09:17:07 music volumio[1014]: info: CoreCommandRouter::Close All Modals sent Oct 09 09:17:07 music volumio[1014]: info: CoreStateMachine::pushState Oct 09 09:17:07 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 09 09:17:07 music volumio[1014]: info: CoreCommandRouter::volumioPushState Oct 09 09:17:07 music volumio[1153]: .........................................++++ Oct 09 09:17:07 music volumio[1153]: e is 65537 (0x010001) Oct 09 09:17:07 music volumio[1153]: writing RSA key Oct 09 09:17:07 music volumio[1014]: info: MyVolumio status changed Oct 09 09:17:07 music volumio[1014]: info: Streaming services startup Oct 09 09:17:07 music volumio[1014]: info: Starting Streaming Daemon Oct 09 09:17:08 music volumio[1014]: info: Removing browser output: myVolumio user plan is not superstar Oct 09 09:17:08 music volumio[1014]: info: Removing audio output: Oct 09 09:17:08 music volumio[1014]: info: Stoppping Tunnel 1 Oct 09 09:17:08 music sudo[1362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 09 09:17:08 music sudo[1362]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 09:17:08 music sudo[1362]: pam_unix(sudo:session): session closed for user root Oct 09 09:17:08 music sudo[1365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 09 09:17:08 music sudo[1365]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 09:17:08 music volumio[1014]: info: Setting Geolocation for MyVolumio to eu10 Oct 09 09:17:08 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 09 09:17:08 music sudo[1365]: pam_unix(sudo:session): session closed for user root Oct 09 09:17:08 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 09 09:17:08 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 09 09:17:08 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 09 09:17:08 music volumio[1014]: info: Remote SSH Stopped Oct 09 09:17:08 music volumio[1014]: error: Cannot start Volumio Streaming Daemon Oct 09 09:17:08 music volumio[1014]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 09 09:17:08 music volumio[1014]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 09 09:17:09 music volumio[1014]: STREAMING PROXY: Starting server on port 3245 Oct 09 09:17:09 music volumio[1014]: Node JS runtime: 14 Oct 09 09:17:09 music sudo[1369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 09 09:17:09 music sudo[1369]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 09:17:09 music sudo[1371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 09 09:17:09 music sudo[1371]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 09 09:17:09 music sudo[1369]: pam_unix(sudo:session): session closed for user root Oct 09 09:17:09 music volumio[1014]: info: Updating MyVolumio device info Oct 09 09:17:09 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 09 09:17:09 music sudo[1371]: pam_unix(sudo:session): session closed for user root Oct 09 09:17:09 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 09 09:17:09 music volumio[1014]: verbose: New Socket.io Connection to 192.168.109.250 from 192.168.109.1 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 6 Oct 09 09:17:09 music volumio[1014]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 09 09:17:09 music volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 09 09:17:09 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 09 09:17:09 music volumio[1014]: info: Listing playlists Oct 09 09:17:09 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 09 09:17:09 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 09 09:17:09 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 09 09:17:09 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 09 09:17:10 music volumio[1014]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 09 09:17:11 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 09 09:17:11 music volumio[1014]: info: Received Get System Info Oct 09 09:17:11 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 09 09:17:11 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 09 09:17:11 music volumio[1014]: info: Discovery: Getting this device information Oct 09 09:17:11 music volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 09 09:17:11 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 09 09:17:12 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 09 09:17:12 music volumio[1014]: info: Received Get System Info Oct 09 09:17:12 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 09 09:17:12 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 09 09:17:12 music volumio[1014]: info: Discovery: Getting this device information Oct 09 09:17:12 music volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 09 09:17:12 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 09 09:17:13 music volumio[1014]: info: MYVOLUMIO: Adding device Oct 09 09:17:13 music volumio[1014]: info: MYVOLUMIO: Evaluating Server Oct 09 09:17:15 music volumio[1014]: info: Setting Geolocation for MyVolumio to eu6 Oct 09 09:17:15 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 09 09:17:15 music volumio[1014]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 09 09:17:16 music volumio[1014]: info: Updating MyVolumio device info Oct 09 09:17:16 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 09 09:17:17 music volumio[1014]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 09 09:17:18 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 09 09:17:18 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 09 09:17:22 music volumio[1014]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 09 09:17:22 music volumio[1014]: info: [ASDebug] Setting UI defaults Oct 09 09:17:22 music volumio[1014]: info: [ASDebug] Port: 14 Oct 09 09:17:22 music volumio[1014]: info: [ASDebug] Inverted: false Oct 09 09:17:22 music volumio[1014]: info: [ASDebug] Latched: false Oct 09 09:17:22 music volumio[1014]: info: [ASDebug] On pulse width: 500 Oct 09 09:17:22 music volumio[1014]: info: [ASDebug] Off pulse width: 500 Oct 09 09:17:26 music volumio[1014]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Oct 09 09:17:26 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Oct 09 09:17:26 music volumio[1014]: info: [ASDebug] Saving Settings: Port: 14 Oct 09 09:17:26 music volumio[1014]: info: [ASDebug] Saving Settings: Inverted: true Oct 09 09:17:26 music volumio[1014]: info: [ASDebug] Saving Settings: Delay: 30000 Oct 09 09:17:26 music volumio[1014]: info: [ASDebug] Saving Settings: Latched: false Oct 09 09:17:26 music volumio[1014]: info: [ASDebug] Saving Settings: On Pulse width: 500 Oct 09 09:17:26 music volumio[1014]: info: [ASDebug] Saving Settings: Off Pulse width: 500 Oct 09 09:17:26 music volumio[1014]: error: Failed callmethod call: TypeError: Cannot read property 'unexport' of undefined Oct 09 09:17:27 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 09 09:17:27 music volumio[1014]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 09 09:17:27 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 09 09:17:27 music volumio[1014]: info: Received Get System Version Oct 09 09:17:27 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 09 09:17:27 music volumio[1014]: info: Received Get System Info Oct 09 09:17:27 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 09 09:17:27 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 09 09:17:27 music volumio[1014]: info: Discovery: Getting this device information Oct 09 09:17:27 music volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 09 09:17:27 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 09 09:17:30 music volumio[1014]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Oct 09 09:17:30 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Oct 09 09:17:30 music volumio[1014]: info: [ASDebug] Saving Settings: Port: 14 Oct 09 09:17:30 music volumio[1014]: info: [ASDebug] Saving Settings: Inverted: false Oct 09 09:17:30 music volumio[1014]: info: [ASDebug] Saving Settings: Delay: 30000 Oct 09 09:17:30 music volumio[1014]: info: [ASDebug] Saving Settings: Latched: false Oct 09 09:17:30 music volumio[1014]: info: [ASDebug] Saving Settings: On Pulse width: 500 Oct 09 09:17:30 music volumio[1014]: info: [ASDebug] Saving Settings: Off Pulse width: 500 Oct 09 09:17:30 music volumio[1014]: error: Failed callmethod call: TypeError: Cannot read property 'unexport' of undefined Oct 09 09:17:32 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 09 09:17:32 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 09 09:17:35 music volumio[1014]: info: Enabling plugin ampswitch Oct 09 09:17:35 music volumio[1014]: info: Loading plugin "ampswitch"... Oct 09 09:17:35 music volumio[1014]: info: Applying required configuration parameters for plugin ampswitch Oct 09 09:17:35 music volumio[1014]: info: PLUGIN START: ampswitch Oct 09 09:17:35 music kernel: export_store: invalid GPIO 14 Oct 09 09:17:35 music volumio[1014]: info: Error: Error: EINVAL: invalid argument, write Oct 09 09:17:41 music volumio[1014]: info: Enabling plugin ampswitch Oct 09 09:17:41 music volumio[1014]: info: Loading plugin "ampswitch"... Oct 09 09:17:41 music volumio[1014]: info: Applying required configuration parameters for plugin ampswitch Oct 09 09:17:41 music volumio[1014]: info: PLUGIN START: ampswitch Oct 09 09:17:41 music kernel: export_store: invalid GPIO 14 Oct 09 09:17:41 music volumio[1014]: info: Error: Error: EINVAL: invalid argument, write Oct 09 09:18:00 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 09 09:18:03 music volumio[1014]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/ampswitch/0.2.0/volumio/buster/armhf Oct 09 09:18:07 music volumio[1014]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/download/ampswitch/0.2.0/volumio/buster/armhf Oct 09 09:18:07 music volumio[1014]: info: Folder /tmp/plugins removed Oct 09 09:18:08 music volumio[1014]: info: Check plugin dependencies Oct 09 09:18:08 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 09 09:18:08 music volumio[1014]: info: Checking if plugin already exists Oct 09 09:18:08 music volumio[1014]: info: An error occurred installing the plugin. Rolling back config Oct 09 09:18:08 music volumio[1014]: info: Plugin folders cleanup Oct 09 09:18:08 music volumio[1014]: info: Scanning into folder /volumio/app/plugins/ Oct 09 09:18:08 music volumio[1014]: info: Scanning category audio_interface Oct 09 09:18:08 music volumio[1014]: info: Scanning category miscellanea Oct 09 09:18:08 music volumio[1014]: info: Scanning category music_service Oct 09 09:18:08 music volumio[1014]: info: Scanning category plugins.json Oct 09 09:18:08 music volumio[1014]: info: Scanning category system_controller Oct 09 09:18:08 music volumio[1014]: info: Scanning category user_interface Oct 09 09:18:08 music volumio[1014]: info: Scanning into folder /data/plugins/ Oct 09 09:18:08 music volumio[1014]: info: Scanning category system_controller Oct 09 09:18:08 music volumio[1014]: info: Plugin folders cleanup completed Oct 09 09:18:08 music volumio[1014]: info: Error: Error Oct 09 09:18:08 music volumio[1014]: info: Folder /tmp/plugins removed Oct 09 09:18:08 music volumio[1014]: info: Folder /tmp/downloaded_plugin.zip removed Oct 09 09:18:08 music volumio[1014]: info: Folder /tmp/downloaded_plugin.zip removed Oct 09 09:18:10 music volumio[1014]: info: Folder /data/temp removed Oct 09 09:18:30 music volumio[1014]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 09 09:18:30 music volumio[1014]: info: [ASDebug] Setting UI defaults Oct 09 09:18:30 music volumio[1014]: info: [ASDebug] Port: 14 Oct 09 09:18:30 music volumio[1014]: info: [ASDebug] Inverted: false Oct 09 09:18:30 music volumio[1014]: info: [ASDebug] Latched: false Oct 09 09:18:30 music volumio[1014]: info: [ASDebug] On pulse width: 500 Oct 09 09:18:30 music volumio[1014]: info: [ASDebug] Off pulse width: 500 Oct 09 09:18:35 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 09 09:18:35 music volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 09 09:18:38 music volumio[1014]: info: Starting Uninstall of plugin system_controller - ampswitch Oct 09 09:18:38 music volumio[1014]: info: Uninstalling plugin ampswitch Oct 09 09:18:38 music volumio[1014]: info: [ASDebug] Port: 14 Oct 09 09:18:38 music volumio[1014]: info: [ASDebug] Inverted: false Oct 09 09:18:38 music volumio[1014]: info: [ASDebug] Delay: 30000 Oct 09 09:18:38 music volumio[1014]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 09 09:18:38 music volumio[1014]: TypeError: Cannot read property 'unexport' of undefined Oct 09 09:18:38 music volumio[1014]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:234:19) Oct 09 09:18:38 music volumio[1014]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:84:10) Oct 09 09:18:38 music volumio[1014]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Oct 09 09:18:38 music volumio[1014]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Oct 09 09:18:38 music volumio[1014]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1465:22) Oct 09 09:18:38 music volumio[1014]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1246:45) Oct 09 09:18:38 music volumio[1014]: at Socket.emit (events.js:315:20) Oct 09 09:18:38 music volumio[1014]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 09 09:18:38 music volumio[1014]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 09 09:18:38 music volumio[1014]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 09 09:18:41 music sudo[1486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-09 09:17 Oct 09 09:18:41 music sudo[1486]: 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="35b821ff7c588ea2ff28d7b2bc9bb02e49e3ab6c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8ef80245b1f7b577b29fe08b77efdc88d53369e5" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 18 Sep 2024 02:17:42 PM CEST" VOLUMIO_VERSION="3.758" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cfa71b4386e95e4d11d00ae35602129a"