-- Logs begin at Thu 2025-12-18 21:58:05 HKT, end at Thu 2025-12-18 22:58:40 HKT. -- Dec 18 22:57:00 volumio volumio[1122]: info: CoreCommandRouter::volumioPause Dec 18 22:57:00 volumio volumio[1122]: info: CoreStateMachine::pause Dec 18 22:57:00 volumio volumio[1122]: info: CoreStateMachine::stPlaybackTimer Dec 18 22:57:00 volumio volumio[1122]: info: CoreStateMachine::servicePause Dec 18 22:57:00 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 Dec 18 22:57:00 volumio volumio[1122]: info: CoreCommandRouter::servicePause Dec 18 22:57:00 volumio volumio[1122]: info: ControllerMpd::pause Dec 18 22:57:00 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand pause Dec 18 22:57:00 volumio volumio[1122]: info: sendMpdCommand pause took 1 milliseconds Dec 18 22:57:00 volumio volumio[1122]: info: Dec 18 22:57:00 volumio volumio[1122]: ---------------------------- MPD announces state update: player Dec 18 22:57:00 volumio volumio[1122]: info: ControllerMpd::getState Dec 18 22:57:00 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status Dec 18 22:57:00 volumio volumio[1122]: info: sendMpdCommand status took 0 milliseconds Dec 18 22:57:00 volumio volumio[1122]: verbose: ControllerMpd::parseState Dec 18 22:57:00 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 22:57:00 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 18 22:57:00 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo Dec 18 22:57:00 volumio volumio[1122]: info: ControllerMpd::pushState Dec 18 22:57:00 volumio volumio[1122]: info: CoreCommandRouter::servicePushState Dec 18 22:57:00 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 Dec 18 22:57:00 volumio volumio[1122]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":20868,"duration":198,"samplerate":"88.2 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"再坐一會","artist":"鄺美雲","album":"極品之選監聽版2-女聲篇","uri":"INTERNAL/【AIFF】/【AIFF】群星 - 2002《極品之選監聽版2》女聲篇/04. 鄺美雲 - 再坐一會.aiff","trackType":"aiff"} Dec 18 22:57:00 volumio volumio[1122]: verbose: CURRENT POSITION 3 Dec 18 22:57:00 volumio volumio[1122]: info: CoreStateMachine::syncState stateService pause Dec 18 22:57:00 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus pause Dec 18 22:57:00 volumio volumio[1122]: info: CoreStateMachine::pushState Dec 18 22:57:00 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 Dec 18 22:57:00 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 22:57:00 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState Dec 18 22:57:00 volumio volumio[1122]: info: CoreStateMachine::stPlaybackTimer Dec 18 22:57:00 volumio volumio[1122]: info: ------------------------------ 8ms Dec 18 22:57:05 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 18 22:57:05 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 18 22:58:17 volumio ntpd[1117]: ntpd exiting on signal 15 (Terminated) Dec 18 22:58:17 volumio ntpd[1117]: 69.89.207.99 local addr 10.10.1.125 -> Dec 18 22:58:17 volumio ntpd[1117]: 23.186.168.130 local addr 10.10.1.125 -> Dec 18 22:58:17 volumio ntpd[1117]: 118.143.17.82 local addr 10.10.1.125 -> Dec 18 22:58:17 volumio ntpd[1117]: 208.113.130.146 local addr 10.10.1.125 -> Dec 18 22:58:17 volumio ntpd[1117]: 223.255.185.2 local addr 10.10.1.125 -> Dec 18 22:58:17 volumio ntpd[1117]: 223.255.185.3 local addr 10.10.1.125 -> Dec 18 22:58:17 volumio systemd[1]: Stopping Network Time Service... Dec 18 22:58:17 volumio systemd[1]: ntp.service: Succeeded. Dec 18 22:58:17 volumio systemd[1]: Stopped Network Time Service. Dec 18 22:58:17 volumio systemd[1]: Starting Network Time Service... Dec 18 22:58:17 volumio ntpd[12389]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 18 22:58:17 volumio ntpd[12389]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Dec 18 22:58:17 volumio systemd[1]: Started Network Time Service. Dec 18 22:58:17 volumio ntpd[12395]: proto: precision = 0.583 usec (-21) Dec 18 22:58:17 volumio ntpd[12395]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 18 22:58:17 volumio ntpd[12395]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 18 22:58:17 volumio ntpd[12395]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1087 days ago Dec 18 22:58:17 volumio ntpd[12395]: Listen and drop on 0 v6wildcard [::]:123 Dec 18 22:58:17 volumio ntpd[12395]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 18 22:58:17 volumio ntpd[12395]: Listen normally on 2 lo 127.0.0.1:123 Dec 18 22:58:17 volumio ntpd[12395]: Listen normally on 3 wlan0 10.10.1.125:123 Dec 18 22:58:17 volumio ntpd[12395]: Listening on routing socket on fd #20 for interface updates Dec 18 22:58:17 volumio ntpd[12395]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Dec 18 22:58:17 volumio ntpd[12395]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Dec 18 22:58:18 volumio ntpd[12395]: Soliciting pool server 17.253.84.253 Dec 18 22:58:19 volumio ntpd[12395]: Soliciting pool server 23.150.41.122 Dec 18 22:58:20 volumio ntpd[12395]: Soliciting pool server 23.168.24.210 Dec 18 22:58:20 volumio ntpd[12395]: Soliciting pool server 5.161.191.31 Dec 18 22:58:21 volumio ntpd[12395]: Soliciting pool server 65.74.89.136 Dec 18 22:58:21 volumio ntpd[12395]: Soliciting pool server 118.143.17.83 Dec 18 22:58:22 volumio ntpd[12395]: Soliciting pool server 223.255.185.3 Dec 18 22:58:22 volumio ntpd[12395]: Soliciting pool server 137.110.222.27 Dec 18 22:58:23 volumio ntpd[12395]: Soliciting pool server 209.58.185.100 Dec 18 22:58:23 volumio ntpd[12395]: Soliciting pool server 198.137.202.56 Dec 18 22:58:24 volumio ntpd[12395]: Soliciting pool server 2400:9380:f245:3400:a589:1e51:da75:145 Dec 18 22:58:32 volumio volumio[1122]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ampswitch/volumio/buster/armhf Dec 18 22:58:34 volumio volumio[1122]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ampswitch/volumio/buster/armhf Dec 18 22:58:34 volumio volumio[1122]: info: Folder /tmp/plugins removed Dec 18 22:58:34 volumio volumio[1122]: info: Check plugin dependencies Dec 18 22:58:34 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 18 22:58:34 volumio volumio[1122]: info: Checking if plugin already exists Dec 18 22:58:34 volumio volumio[1122]: info: Rename folder Dec 18 22:58:34 volumio volumio[1122]: info: Folder /tmp/downloaded_plugin.zip removed Dec 18 22:58:34 volumio volumio[1122]: info: Move to category Dec 18 22:58:34 volumio volumio[1122]: info: Checking if install.sh is present Dec 18 22:58:34 volumio volumio[1122]: info: Executing install.sh Dec 18 22:58:34 volumio sudo[12446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_controller/ampswitch/install.sh Dec 18 22:58:34 volumio sudo[12446]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 22:58:34 volumio sudo[12446]: pam_unix(sudo:session): session closed for user root Dec 18 22:58:34 volumio volumio[1122]: Plugin install end detected on script Dec 18 22:58:34 volumio volumio[1122]: info: Install script completed Dec 18 22:58:34 volumio volumio[1122]: info: Adding reference to registry Dec 18 22:58:34 volumio volumio[1122]: info: Done installing plugin. Dec 18 22:58:34 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 18 22:58:34 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 18 22:58:35 volumio volumio[1122]: info: Folder /tmp/plugins removed Dec 18 22:58:35 volumio volumio[1122]: info: Folder /tmp/downloaded_plugin.zip removed Dec 18 22:58:35 volumio volumio[1122]: info: Folder /data/temp removed Dec 18 22:58:36 volumio volumio[1122]: info: Enabling plugin ampswitch Dec 18 22:58:36 volumio volumio[1122]: info: Loading plugin "ampswitch"... Dec 18 22:58:36 volumio volumio[1122]: info: PLUGIN START: ampswitch Dec 18 22:58:36 volumio volumio[1122]: info: Error: Error: EINVAL: invalid argument, write Dec 18 22:58:36 volumio kernel: rockchip-pinctrl pinctrl: pin 0 is unrouted Dec 18 22:58:36 volumio volumio[1122]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 18 22:58:39 volumio volumio[1122]: info: Enabling plugin ampswitch Dec 18 22:58:39 volumio volumio[1122]: info: Loading plugin "ampswitch"... Dec 18 22:58:39 volumio volumio[1122]: info: Applying required configuration parameters for plugin ampswitch Dec 18 22:58:39 volumio volumio[1122]: info: PLUGIN START: ampswitch Dec 18 22:58:39 volumio volumio[1122]: info: Done. Dec 18 22:58:39 volumio volumio[1122]: info: CoreCommandRouter::volumioGetState Dec 18 22:58:39 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 Dec 18 22:58:39 volumio kernel: rockchip-pinctrl pinctrl: pin 0 is unrouted Dec 18 22:58:39 volumio volumio[1122]: info: [ASDebug] CurState: pause PrevState: na Dec 18 22:58:39 volumio volumio[1122]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Dec 18 22:58:39 volumio volumio[1122]: info: [ASDebug] CurState: pause PrevState: na Dec 18 22:58:39 volumio volumio[1122]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Dec 18 22:58:40 volumio volumio[1122]: info: [ASDebug] Togle GPIO: OFF Dec 18 22:58:40 volumio volumio[1122]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 22:58:40 volumio volumio[1122]: Error: EPERM: operation not permitted, write Dec 18 22:58:40 volumio volumio[1122]: at Object.writeSync (fs.js:737:3) Dec 18 22:58:40 volumio volumio[1122]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Dec 18 22:58:40 volumio volumio[1122]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Dec 18 22:58:40 volumio volumio[1122]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Dec 18 22:58:40 volumio volumio[1122]: at listOnTimeout (internal/timers.js:557:17) Dec 18 22:58:40 volumio volumio[1122]: at processTimers (internal/timers.js:500:7) { Dec 18 22:58:40 volumio volumio[1122]: errno: -1, Dec 18 22:58:40 volumio volumio[1122]: syscall: 'write', Dec 18 22:58:40 volumio volumio[1122]: code: 'EPERM' Dec 18 22:58:40 volumio volumio[1122]: } Dec 18 22:58:40 volumio volumio[1122]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 22:58:40 volumio sudo[12495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-18 22:57 Dec 18 22:58:40 volumio sudo[12495]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 07:17:52 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="3b20d491450ccf59e0e519a97572dc60"