-- Logs begin at Thu 2019-02-14 13:11:58 +03, end at Tue 2025-11-04 21:51:43 +03. -- Nov 04 21:50:05 volumio go-librespot[12534]: time="2025-11-04T21:50:05+03:00" level=trace msg="sent dealer ping" Nov 04 21:50:05 volumio go-librespot[12534]: time="2025-11-04T21:50:05+03:00" level=trace msg="received dealer pong" Nov 04 21:50:07 volumio go-librespot[12534]: time="2025-11-04T21:50:07+03:00" level=debug msg="fetched chunk 4/22, size: 524288" uri="spotify:track:6YdrbNhac1najugNMyJhOl" Nov 04 21:50:17 volumio volumio[2717]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 04 21:50:17 volumio volumio[2717]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 04 21:50:19 volumio go-librespot[12534]: time="2025-11-04T21:50:19+03:00" level=debug msg="fetched chunk 5/22, size: 524288" uri="spotify:track:6YdrbNhac1najugNMyJhOl" Nov 04 21:50:31 volumio go-librespot[12534]: time="2025-11-04T21:50:31+03:00" level=debug msg="fetched chunk 6/22, size: 524288" uri="spotify:track:6YdrbNhac1najugNMyJhOl" Nov 04 21:50:34 volumio volumio[2717]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/youtube2/volumio/buster/armhf Nov 04 21:50:35 volumio go-librespot[12534]: time="2025-11-04T21:50:35+03:00" level=trace msg="sent dealer ping" Nov 04 21:50:35 volumio go-librespot[12534]: time="2025-11-04T21:50:35+03:00" level=trace msg="received dealer pong" Nov 04 21:50:37 volumio volumio[2717]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/youtube2/volumio/buster/armhf Nov 04 21:50:37 volumio volumio[2717]: info: Folder /tmp/plugins removed Nov 04 21:50:38 volumio volumio[2717]: info: Check plugin dependencies Nov 04 21:50:38 volumio volumio[2717]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 04 21:50:38 volumio volumio[2717]: info: Checking if plugin already exists Nov 04 21:50:38 volumio volumio[2717]: info: Rename folder Nov 04 21:50:38 volumio volumio[2717]: info: Folder /tmp/downloaded_plugin.zip removed Nov 04 21:50:38 volumio volumio[2717]: info: Move to category Nov 04 21:50:42 volumio volumio[2717]: info: Checking if install.sh is present Nov 04 21:50:42 volumio volumio[2717]: info: Executing install.sh Nov 04 21:50:42 volumio sudo[26235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/youtube2/install.sh Nov 04 21:50:42 volumio sudo[26235]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 21:50:42 volumio sudo[26235]: pam_unix(sudo:session): session closed for user root Nov 04 21:50:42 volumio volumio[2717]: info: YouTube2 plugin installed Nov 04 21:50:42 volumio volumio[2717]: Plugin install end detected on script Nov 04 21:50:42 volumio volumio[2717]: info: Install script completed Nov 04 21:50:42 volumio volumio[2717]: info: Adding reference to registry Nov 04 21:50:42 volumio volumio[2717]: info: Done installing plugin. Nov 04 21:50:42 volumio volumio[2717]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 04 21:50:42 volumio volumio[2717]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 04 21:50:42 volumio volumio[2717]: info: Folder /tmp/plugins removed Nov 04 21:50:42 volumio volumio[2717]: info: Folder /tmp/downloaded_plugin.zip removed Nov 04 21:50:42 volumio volumio[2717]: info: Folder /data/temp removed Nov 04 21:50:44 volumio go-librespot[12534]: time="2025-11-04T21:50:44+03:00" level=debug msg="fetched chunk 7/22, size: 524288" uri="spotify:track:6YdrbNhac1najugNMyJhOl" Nov 04 21:50:51 volumio volumio[2717]: info: CorePlayQueue::getTrack 7 Nov 04 21:50:51 volumio volumio[2717]: info: CorePlayQueue::getTrack 8 Nov 04 21:50:51 volumio volumio[2717]: info: Prefetching next song Nov 04 21:50:51 volumio volumio[2717]: info: [1762282251796] ControllerSpotify::prefetch Nov 04 21:50:51 volumio volumio[2717]: info: Sending Spotify command with payload to local API: /player/add_to_queue Nov 04 21:50:51 volumio go-librespot[12534]: time="2025-11-04T21:50:51+03:00" level=error msg="failed fetching next tracks" error="failed moving to next index 10 (page 1): failed fetching page: invalid status code from page at artistplaycontext/v1/page/spotify/artist-top-tracks-extensions/6z7oJJfl0VPdH5sFgogqMq?exclude_uri=spotify:track:4EpzEnVmHz4rOe2qEkfEsM,spotify:track:3Ni6BTLYg50IH7OW1WwuEk,spotify:track:37phRDyJK8f3xpY240NWX1,spotify:track:6wx9Iq2fYdJaJNYqvxNI3i,spotify:track:6YdrbNhac1najugNMyJhOl,spotify:track:5Q7owz7OiiqBgSkZBescrA,spotify:track:29X7U0hjnEfvinRPNu8a1r,spotify:track:5o81JiWnVQZzner6q92SXp,spotify:track:5uIv9tIAKCXTrqMxd991w4,spotify:track:3k2ClOXdglpX6nBAfgmtRE: 404" uri="spotify:artist:6z7oJJfl0VPdH5sFgogqMq" Nov 04 21:50:51 volumio go-librespot[12534]: time="2025-11-04T21:50:51+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 04 21:50:52 volumio go-librespot[12534]: time="2025-11-04T21:50:52+03:00" level=trace msg="scheduling prefetch in 203s" Nov 04 21:50:56 volumio volumio[2717]: info: CoreStateMachine::startPlaybackTimer Nov 04 21:50:56 volumio volumio[2717]: info: CorePlayQueue::getTrack 8 Nov 04 21:50:56 volumio volumio[2717]: info: CoreStateMachine::pushState Nov 04 21:50:56 volumio volumio[2717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 21:50:56 volumio volumio[2717]: info: CoreCommandRouter::volumioPushState Nov 04 21:50:56 volumio volumio[2717]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Nov 04 21:50:56 volumio volumio[2717]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Nov 04 21:50:57 volumio go-librespot[12534]: time="2025-11-04T21:50:57+03:00" level=debug msg="fetched chunk 8/22, size: 524288" uri="spotify:track:6YdrbNhac1najugNMyJhOl" Nov 04 21:51:02 volumio volumio[2717]: info: Enabling plugin youtube2 Nov 04 21:51:02 volumio volumio[2717]: info: Loading plugin "youtube2"... Nov 04 21:51:04 volumio volumio[2717]: info: PLUGIN START: youtube2 Nov 04 21:51:04 volumio volumio[2717]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 04 21:51:04 volumio volumio[2717]: info: [1762282264473] CoreMusicLibrary::Adding element YouTube2 Nov 04 21:51:04 volumio volumio[2717]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 04 21:51:04 volumio volumio[2717]: Cannot find translation for source YouTube Music Nov 04 21:51:04 volumio volumio[2717]: Cannot find translation for source 80s80s Radio Nov 04 21:51:04 volumio volumio[2717]: Cannot find translation for source Spotify Nov 04 21:51:04 volumio volumio[2717]: Cannot find translation for source YouTube2 Nov 04 21:51:04 volumio volumio[2717]: info: Done. Nov 04 21:51:05 volumio go-librespot[12534]: time="2025-11-04T21:51:05+03:00" level=trace msg="sent dealer ping" Nov 04 21:51:05 volumio go-librespot[12534]: time="2025-11-04T21:51:05+03:00" level=trace msg="received dealer pong" Nov 04 21:51:10 volumio go-librespot[12534]: time="2025-11-04T21:51:10+03:00" level=debug msg="fetched chunk 9/22, size: 524288" uri="spotify:track:6YdrbNhac1najugNMyJhOl" Nov 04 21:51:22 volumio go-librespot[12534]: time="2025-11-04T21:51:22+03:00" level=debug msg="fetched chunk 10/22, size: 524288" uri="spotify:track:6YdrbNhac1najugNMyJhOl" Nov 04 21:51:24 volumio volumio[2717]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ampswitch/volumio/buster/armhf Nov 04 21:51:26 volumio volumio[2717]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ampswitch/volumio/buster/armhf Nov 04 21:51:26 volumio volumio[2717]: info: Folder /tmp/plugins removed Nov 04 21:51:26 volumio volumio[2717]: info: Check plugin dependencies Nov 04 21:51:26 volumio volumio[2717]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 04 21:51:26 volumio volumio[2717]: info: Checking if plugin already exists Nov 04 21:51:26 volumio volumio[2717]: info: Rename folder Nov 04 21:51:26 volumio volumio[2717]: info: Folder /tmp/downloaded_plugin.zip removed Nov 04 21:51:26 volumio volumio[2717]: info: Move to category Nov 04 21:51:27 volumio volumio[2717]: info: Checking if install.sh is present Nov 04 21:51:27 volumio volumio[2717]: info: Executing install.sh Nov 04 21:51:27 volumio sudo[26387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_controller/ampswitch/install.sh Nov 04 21:51:27 volumio sudo[26387]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 21:51:27 volumio sudo[26387]: pam_unix(sudo:session): session closed for user root Nov 04 21:51:27 volumio volumio[2717]: Plugin install end detected on script Nov 04 21:51:27 volumio volumio[2717]: info: Install script completed Nov 04 21:51:27 volumio volumio[2717]: info: Adding reference to registry Nov 04 21:51:27 volumio volumio[2717]: info: Done installing plugin. Nov 04 21:51:27 volumio volumio[2717]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 04 21:51:27 volumio volumio[2717]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 04 21:51:27 volumio volumio[2717]: info: Folder /tmp/plugins removed Nov 04 21:51:27 volumio volumio[2717]: info: Folder /tmp/downloaded_plugin.zip removed Nov 04 21:51:27 volumio volumio[2717]: info: Folder /data/temp removed Nov 04 21:51:32 volumio volumio[2717]: info: Enabling plugin ampswitch Nov 04 21:51:32 volumio volumio[2717]: info: Loading plugin "ampswitch"... Nov 04 21:51:32 volumio volumio[2717]: info: PLUGIN START: ampswitch Nov 04 21:51:32 volumio volumio[2717]: info: Error: Error: EINVAL: invalid argument, write Nov 04 21:51:32 volumio kernel: rockchip-pinctrl pinctrl: pin 0 is unrouted Nov 04 21:51:32 volumio volumio[2717]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Nov 04 21:51:35 volumio go-librespot[12534]: time="2025-11-04T21:51:35+03:00" level=trace msg="sent dealer ping" Nov 04 21:51:35 volumio go-librespot[12534]: time="2025-11-04T21:51:35+03:00" level=trace msg="received dealer pong" Nov 04 21:51:35 volumio go-librespot[12534]: time="2025-11-04T21:51:35+03:00" level=trace msg="received accesspoint ping" Nov 04 21:51:36 volumio go-librespot[12534]: time="2025-11-04T21:51:36+03:00" level=trace msg="received accesspoint pong ack" Nov 04 21:51:36 volumio go-librespot[12534]: time="2025-11-04T21:51:36+03:00" level=debug msg="fetched chunk 11/22, size: 524288" uri="spotify:track:6YdrbNhac1najugNMyJhOl" Nov 04 21:51:42 volumio volumio[2717]: info: Enabling plugin ampswitch Nov 04 21:51:42 volumio volumio[2717]: info: Loading plugin "ampswitch"... Nov 04 21:51:42 volumio volumio[2717]: info: Applying required configuration parameters for plugin ampswitch Nov 04 21:51:42 volumio volumio[2717]: info: PLUGIN START: ampswitch Nov 04 21:51:42 volumio volumio[2717]: info: Done. Nov 04 21:51:42 volumio kernel: rockchip-pinctrl pinctrl: pin 0 is unrouted Nov 04 21:51:42 volumio volumio[2717]: info: CoreCommandRouter::volumioGetState Nov 04 21:51:42 volumio volumio[2717]: info: [ASDebug] CurState: play PrevState: na Nov 04 21:51:42 volumio volumio[2717]: info: [ASDebug] Togle GPIO: ON Nov 04 21:51:42 volumio volumio[2717]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 21:51:42 volumio volumio[2717]: Error: EPERM: operation not permitted, write Nov 04 21:51:42 volumio volumio[2717]: at Object.writeSync (fs.js:737:3) Nov 04 21:51:42 volumio volumio[2717]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Nov 04 21:51:42 volumio volumio[2717]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:207:23) Nov 04 21:51:42 volumio volumio[2717]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:190:86) Nov 04 21:51:42 volumio volumio[2717]: at Socket.on (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:65:8) Nov 04 21:51:42 volumio volumio[2717]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Nov 04 21:51:42 volumio volumio[2717]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10) Nov 04 21:51:42 volumio volumio[2717]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12) Nov 04 21:51:42 volumio volumio[2717]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Nov 04 21:51:42 volumio volumio[2717]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Nov 04 21:51:42 volumio volumio[2717]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8) Nov 04 21:51:42 volumio volumio[2717]: at Decoder. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Nov 04 21:51:42 volumio volumio[2717]: at Decoder.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Nov 04 21:51:42 volumio volumio[2717]: at Decoder.add (/data/plugins/system_controller/ampswitch/node_modules/socket.io-parser/index.js:254:12) Nov 04 21:51:42 volumio volumio[2717]: at Manager.ondata (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:339:16) Nov 04 21:51:42 volumio volumio[2717]: at Socket. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) { Nov 04 21:51:42 volumio volumio[2717]: errno: -1, Nov 04 21:51:42 volumio volumio[2717]: syscall: 'write', Nov 04 21:51:42 volumio volumio[2717]: code: 'EPERM' Nov 04 21:51:42 volumio volumio[2717]: } Nov 04 21:51:42 volumio volumio[2717]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 21:51:43 volumio sudo[26440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-04 21:50 Nov 04 21:51:43 volumio sudo[26440]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:55:15 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="2f3686ade78abf3b92c180a0c5b20c6b"