-- Logs begin at Sun 2024-11-24 22:01:57 +07, end at Sun 2024-11-24 23:04:32 +07. -- Nov 24 23:03:00 volumio go-librespot[12395]: time="2024-11-24T23:03:00+07:00" level=debug msg="fetched chunk 13/18, size: 524288" uri="spotify:track:1F1grkmzE4SGUdsXpIp7Kw" Nov 24 23:03:05 volumio volumio[5381]: error: error Nov 24 23:03:11 volumio volumio[5381]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 23:03:11 volumio volumio[5381]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 23:03:11 volumio volumio[5381]: info: Discovery: Getting this device information Nov 24 23:03:11 volumio volumio[5381]: info: CoreCommandRouter::volumioGetState Nov 24 23:03:11 volumio volumio[5381]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 23:03:12 volumio volumio[5381]: verbose: New Socket.io Connection to 192.168.1.37 from 192.168.1.26 UA: unknown Total Clients: 7 Nov 24 23:03:12 volumio volumio[5381]: info: CoreCommandRouter::volumioGetState Nov 24 23:03:12 volumio volumio[5381]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 24 23:03:12 volumio volumio[5381]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 24 23:03:14 volumio go-librespot[12395]: time="2024-11-24T23:03:14+07:00" level=debug msg="fetched chunk 14/18, size: 524288" uri="spotify:track:1F1grkmzE4SGUdsXpIp7Kw" Nov 24 23:03:18 volumio sudo[12698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 24 23:03:18 volumio sudo[12698]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 23:03:18 volumio sudo[12698]: pam_unix(sudo:session): session closed for user root Nov 24 23:03:18 volumio sudo[12701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 24 23:03:18 volumio sudo[12701]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 23:03:18 volumio sudo[12701]: pam_unix(sudo:session): session closed for user root Nov 24 23:03:18 volumio volumio[5381]: verbose: New Socket.io Connection to 192.168.1.37 from 192.168.1.26 UA: Mozilla/5.0 (iPad; CPU OS 10_3_4 like Mac OS X) AppleWebKit/603.3.8 (KHTML, like Gecko) Mobile/14G61 Total Clients: 8 Nov 24 23:03:20 volumio volumio[5381]: info: CoreCommandRouter::volumioGetState Nov 24 23:03:21 volumio volumio[5381]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 24 23:03:21 volumio volumio[5381]: info: CoreCommandRouter::volumioGetVisibleSources Nov 24 23:03:21 volumio volumio[5381]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 23:03:21 volumio volumio[5381]: info: Listing playlists Nov 24 23:03:21 volumio volumio[5381]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 24 23:03:21 volumio volumio[5381]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 24 23:03:21 volumio volumio[5381]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 24 23:03:21 volumio volumio[5381]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 24 23:03:27 volumio go-librespot[12395]: time="2024-11-24T23:03:27+07:00" level=debug msg="fetched chunk 15/18, size: 524288" uri="spotify:track:1F1grkmzE4SGUdsXpIp7Kw" Nov 24 23:03:27 volumio volumio[5381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 24 23:03:27 volumio volumio[5381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 24 23:03:29 volumio ntpd[9779]: Soliciting pool server 115.165.161.155 Nov 24 23:03:29 volumio ntpd[9779]: Soliciting pool server 103.199.19.135 Nov 24 23:03:30 volumio volumio[5381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 24 23:03:30 volumio volumio[5381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 24 23:03:31 volumio volumio[5381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 24 23:03:34 volumio ntpd[9779]: Soliciting pool server 103.184.124.23 Nov 24 23:03:35 volumio ntpd[9779]: Soliciting pool server 103.130.217.41 Nov 24 23:03:38 volumio volumio[5381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 24 23:03:38 volumio volumio[5381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 24 23:03:40 volumio volumio[5381]: info: CorePlayQueue::getTrack 2 Nov 24 23:03:40 volumio volumio[5381]: info: CorePlayQueue::getTrack 3 Nov 24 23:03:40 volumio volumio[5381]: info: Prefetching next song Nov 24 23:03:40 volumio volumio[5381]: info: [1732464220377] ControllerSpotify::prefetch Nov 24 23:03:40 volumio volumio[5381]: info: Sending Spotify command with payload to local API: /player/add_to_queue Nov 24 23:03:40 volumio go-librespot[12395]: time="2024-11-24T23:03:40+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 24 23:03:40 volumio go-librespot[12395]: time="2024-11-24T23:03:40+07:00" level=trace msg="scheduling prefetch in 49s" Nov 24 23:03:40 volumio go-librespot[12395]: time="2024-11-24T23:03:40+07:00" level=debug msg="fetched chunk 16/18, size: 524288" uri="spotify:track:1F1grkmzE4SGUdsXpIp7Kw" Nov 24 23:03:43 volumio volumio[5381]: info: CoreCommandRouter::volumioGetState Nov 24 23:03:44 volumio volumio[5381]: info: CoreStateMachine::startPlaybackTimer Nov 24 23:03:44 volumio volumio[5381]: info: CorePlayQueue::getTrack 3 Nov 24 23:03:47 volumio volumio[5381]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 23:03:54 volumio go-librespot[12395]: time="2024-11-24T23:03:54+07:00" level=debug msg="fetched chunk 17/18, size: 524288" uri="spotify:track:1F1grkmzE4SGUdsXpIp7Kw" Nov 24 23:03:55 volumio volumio[5381]: error: error Nov 24 23:04:04 volumio volumio[5381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 24 23:04:04 volumio volumio[5381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 24 23:04:08 volumio volumio[5381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 24 23:04:08 volumio volumio[5381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 24 23:04:08 volumio go-librespot[12395]: time="2024-11-24T23:04:08+07:00" level=debug msg="fetched chunk 18/18, size: 413097" uri="spotify:track:1F1grkmzE4SGUdsXpIp7Kw" Nov 24 23:04:14 volumio volumio[5381]: info: CoreCommandRouter::volumioGetState Nov 24 23:04:14 volumio volumio[5381]: info: Enabling plugin ampswitch Nov 24 23:04:14 volumio volumio[5381]: info: Loading plugin "ampswitch"... Nov 24 23:04:14 volumio volumio[5381]: info: Applying required configuration parameters for plugin ampswitch Nov 24 23:04:14 volumio volumio[5381]: info: PLUGIN START: ampswitch Nov 24 23:04:14 volumio kernel: export_store: invalid GPIO 24 Nov 24 23:04:14 volumio volumio[5381]: info: Error: Error: EINVAL: invalid argument, write Nov 24 23:04:22 volumio volumio[5381]: info: Enabling plugin ampswitch Nov 24 23:04:22 volumio volumio[5381]: info: Loading plugin "ampswitch"... Nov 24 23:04:22 volumio volumio[5381]: info: Applying required configuration parameters for plugin ampswitch Nov 24 23:04:22 volumio volumio[5381]: info: PLUGIN START: ampswitch Nov 24 23:04:22 volumio kernel: export_store: invalid GPIO 24 Nov 24 23:04:22 volumio volumio[5381]: info: Error: Error: EINVAL: invalid argument, write Nov 24 23:04:29 volumio go-librespot[12395]: time="2024-11-24T23:04:29+07:00" level=debug msg="prefetching next track" uri="spotify:track:7rQUKFLXPOIYpvFKNddRcS" Nov 24 23:04:29 volumio go-librespot[12395]: time="2024-11-24T23:04:29+07:00" level=debug msg="selected format OGG_VORBIS_320 (5d9b2493a87db61f2d4d6db8c9d82a30675db3e9)" uri="spotify:track:7rQUKFLXPOIYpvFKNddRcS" Nov 24 23:04:29 volumio go-librespot[12395]: time="2024-11-24T23:04:29+07:00" level=debug msg="requested aes key for file 5d9b2493a87db61f2d4d6db8c9d82a30675db3e9, gid: 7rQUKFLXPOIYpvFKNddRcS" Nov 24 23:04:30 volumio go-librespot[12395]: time="2024-11-24T23:04:30+07:00" level=debug msg="fetched first chunk of 24, total size is 12110376 bytes" uri="spotify:track:7rQUKFLXPOIYpvFKNddRcS" Nov 24 23:04:30 volumio go-librespot[12395]: time="2024-11-24T23:04:30+07:00" level=warning msg="reducing track normalisation factor to prevent clipping, please add negative pregain to avoid" Nov 24 23:04:30 volumio go-librespot[12395]: time="2024-11-24T23:04:30+07:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:7rQUKFLXPOIYpvFKNddRcS" Nov 24 23:04:30 volumio go-librespot[12395]: time="2024-11-24T23:04:30+07:00" level=info msg="prefetched track \"Xuân Này Con Không Về\" (duration: 305920ms)" uri="spotify:track:7rQUKFLXPOIYpvFKNddRcS" Nov 24 23:04:30 volumio go-librespot[12395]: time="2024-11-24T23:04:30+07:00" level=debug msg="fetched chunk 1/23, size: 524288" uri="spotify:track:7rQUKFLXPOIYpvFKNddRcS" Nov 24 23:04:30 volumio go-librespot[12395]: time="2024-11-24T23:04:30+07:00" level=debug msg="fetched chunk 3/23, size: 524288" uri="spotify:track:7rQUKFLXPOIYpvFKNddRcS" Nov 24 23:04:30 volumio go-librespot[12395]: time="2024-11-24T23:04:30+07:00" level=debug msg="fetched chunk 2/23, size: 524288" uri="spotify:track:7rQUKFLXPOIYpvFKNddRcS" Nov 24 23:04:31 volumio volumio[5381]: info: Starting Uninstall of plugin system_controller - ampswitch Nov 24 23:04:31 volumio volumio[5381]: info: Uninstalling plugin ampswitch Nov 24 23:04:31 volumio volumio[5381]: info: [ASDebug] Port: 24 Nov 24 23:04:31 volumio volumio[5381]: info: [ASDebug] Inverted: false Nov 24 23:04:31 volumio volumio[5381]: info: [ASDebug] Delay: 720 Nov 24 23:04:31 volumio volumio[5381]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 23:04:31 volumio volumio[5381]: TypeError: Cannot read property 'unexport' of undefined Nov 24 23:04:31 volumio volumio[5381]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Nov 24 23:04:31 volumio volumio[5381]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Nov 24 23:04:31 volumio volumio[5381]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Nov 24 23:04:31 volumio volumio[5381]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1435:10) Nov 24 23:04:31 volumio volumio[5381]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1424:22) Nov 24 23:04:31 volumio volumio[5381]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1103:45) Nov 24 23:04:31 volumio volumio[5381]: at Socket.emit (events.js:400:28) Nov 24 23:04:31 volumio volumio[5381]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Nov 24 23:04:31 volumio volumio[5381]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Nov 24 23:04:31 volumio volumio[5381]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 23:04:32 volumio sudo[12805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-24 23:03 Nov 24 23:04:32 volumio sudo[12805]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="613d705c083fd29de3027cf6e63a923a6020da8e" VOLUMIO_FE_VERSION="575f38b17bfc836dbd47f560a440d36ecd98d5af" VOLUMIO_FE3_VERSION="af818e7eb038d49989134ddd7bf2b2ffaa8cf083" VOLUMIO_BE_VERSION="5936439c04ed803864b9ef314729c659b841752e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 29 Jan 2022 12:36:00 AM CET" VOLUMIO_VERSION="3.212" VOLUMIO_HARDWARE="kvim1" VOLUMIO_DEVICENAME="Khadas VIM1" VOLUMIO_HASH="a41712c8cd708e7cae9978278a240084"