-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Wed 2025-04-30 19:51:03 IST. -- Apr 30 19:50:04 volumio volumio-time-update[612]: volumio-time-update-util: Fetching time from Volumio... Apr 30 19:50:05 volumio dhcpcd[1107]: eth0: offered 192.168.2.189 from 192.168.2.1 Apr 30 19:50:05 volumio dhcpcd[663]: eth0: offered 192.168.2.189 from 192.168.2.1 Apr 30 19:50:05 volumio dhcpcd[1107]: eth0: probing address 192.168.2.189/24 Apr 30 19:50:05 volumio dhcpcd[663]: eth0: probing address 192.168.2.189/24 Apr 30 19:50:05 volumio volumio[1595]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 30 19:50:09 volumio dhcpcd[1107]: eth0: leased 192.168.2.189 for 600 seconds Apr 30 19:50:09 volumio avahi-daemon[608]: Registering new address record for 192.168.2.189 on eth0.IPv4. Apr 30 19:50:09 volumio dhcpcd[1107]: eth0: adding route to 192.168.2.0/24 Apr 30 19:50:09 volumio dhcpcd[1107]: eth0: changing default route via 192.168.2.1 Apr 30 19:50:09 volumio dhcpcd[663]: eth0: deleted default route Apr 30 19:50:09 volumio dnsmasq[1032]: reading /etc/resolv.conf Apr 30 19:50:09 volumio dnsmasq[1032]: using nameserver 8.8.8.8#53 Apr 30 19:50:09 volumio dnsmasq[1032]: using nameserver 8.8.4.4#53 Apr 30 19:50:09 volumio dnsmasq[1032]: using nameserver 208.67.222.222#53 Apr 30 19:50:09 volumio dnsmasq[1032]: using nameserver 208.67.220.220#53 Apr 30 19:50:09 volumio dhcpcd[663]: eth0: leased 192.168.2.189 for 600 seconds Apr 30 19:50:09 volumio dhcpcd[663]: eth0: adding route to 192.168.2.0/24 Apr 30 19:50:09 volumio dhcpcd[663]: eth0: adding default route via 192.168.2.1 Apr 30 19:50:11 volumio ntpd[830]: Listen normally on 6 eth0 192.168.2.189:123 Apr 30 19:50:11 volumio ntpd[830]: new interface(s) found: waking up resolver Apr 30 19:50:11 volumio volumio[1595]: .....................................................................................................................++++ Apr 30 19:50:11 volumio ntpd[830]: ntpd exiting on signal 15 (Terminated) Apr 30 19:50:11 volumio systemd[1]: Stopping Network Time Service... Apr 30 19:50:11 volumio dhcpcd[1107]: eth0: deleting route to 169.254.0.0/16 Apr 30 19:50:11 volumio avahi-daemon[608]: Withdrawing address record for 169.254.16.86 on eth0. Apr 30 19:50:11 volumio avahi-daemon[608]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.16.86. Apr 30 19:50:11 volumio avahi-daemon[608]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.2.189. Apr 30 19:50:11 volumio systemd[1]: ntp.service: Succeeded. Apr 30 19:50:11 volumio systemd[1]: Stopped Network Time Service. Apr 30 19:50:11 volumio systemd[1]: Starting Network Time Service... Apr 30 19:50:11 volumio ntpd[1786]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 30 19:50:11 volumio ntpd[1786]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Apr 30 19:50:11 volumio systemd[1]: Started Network Time Service. Apr 30 19:50:11 volumio dhcpcd[663]: eth0: deleting route to 169.254.0.0/16 Apr 30 19:50:11 volumio ntpd[1792]: proto: precision = 0.583 usec (-21) Apr 30 19:50:11 volumio ntpd[1792]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 30 19:50:11 volumio ntpd[1792]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 30 19:50:11 volumio ntpd[1792]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 855 days ago Apr 30 19:50:11 volumio ntpd[1792]: Listen and drop on 0 v6wildcard [::]:123 Apr 30 19:50:11 volumio ntpd[1792]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 30 19:50:11 volumio ntpd[1792]: Listen normally on 2 lo 127.0.0.1:123 Apr 30 19:50:11 volumio ntpd[1792]: Listen normally on 3 eth0 192.168.2.189:123 Apr 30 19:50:11 volumio ntpd[1792]: Listen normally on 4 wlan0 192.168.211.1:123 Apr 30 19:50:11 volumio ntpd[1792]: Listen normally on 5 wlan0 169.254.161.193:123 Apr 30 19:50:11 volumio ntpd[1792]: Listening on routing socket on fd #22 for interface updates Apr 30 19:50:11 volumio ntpd[1792]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 30 19:50:11 volumio ntpd[1792]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 30 19:50:14 volumio volumio[1009]: error: MyVolumio Plugin failed to start in a timely fashion Apr 30 19:50:14 volumio volumio[1009]: [Metrics] CommandRouter: 33s 969.20ms Apr 30 19:50:14 volumio volumio[1009]: info: CoreCommandRouter::volumiosetStartupVolume Apr 30 19:50:14 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 19:50:14 volumio volumio[1009]: info: VolumeController:: Setting startup Volume undefined Apr 30 19:50:14 volumio volumio[1009]: info: VolumeController::SetAlsaVolumeNaN Apr 30 19:50:14 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 19:50:14 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Apr 30 19:50:14 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Apr 30 19:50:14 volumio volumio[1009]: info: CoreStateMachine::pushState Apr 30 19:50:14 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 30 19:50:14 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 19:50:14 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Apr 30 19:50:14 volumio volumio[1009]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Apr 30 19:50:14 volumio volumio[1009]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Apr 30 19:50:14 volumio volumio[1009]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Apr 30 19:50:14 volumio volumio[1009]: aplay: main:828: audio open error: No such device Apr 30 19:50:15 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 30 19:50:15 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 30 19:50:15 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Apr 30 19:50:15 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 30 19:50:19 volumio volumio-time-update[612]: volumio-time-update-util: Date not found in response Apr 30 19:50:19 volumio volumio-time-update[612]: volumio-time-update-util: Retrying in 5 seconds... Apr 30 19:50:20 volumio volumio[1595]: ................................................................................................................................................................++++ Apr 30 19:50:20 volumio volumio[1595]: e is 65537 (0x010001) Apr 30 19:50:20 volumio volumio[1595]: writing RSA key Apr 30 19:50:21 volumio volumio[1009]: info: BOOT COMPLETED Apr 30 19:50:23 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 30 19:50:23 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 19:50:23 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 30 19:50:23 volumio volumio[1009]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=tinkerboard&variante=volumio&version=3.804&uuid=5378fd80de9ae9a702fa3f4c2ef55c7d" http://updates.volumio.org/downloader-v1/track-device Apr 30 19:50:23 volumio volumio[1009]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 30 19:50:23 volumio volumio[1009]: Dload Upload Total Spent Left Speed Apr 30 19:50:23 volumio volumio[1009]: [1.6K blob data] Apr 30 19:50:23 volumio volumio[1009]: retrying in 5 seconds, trial 0 Apr 30 19:50:23 volumio volumio[1009]: info: Volumio Calling Home Apr 30 19:50:23 volumio volumio[1009]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 2 Apr 30 19:50:24 volumio volumio-time-update[612]: volumio-time-update-util: Fetching time from Volumio... Apr 30 19:50:27 volumio volumio[1009]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 3 Apr 30 19:50:27 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 30 19:50:28 volumio volumio[1009]: info: CoreCommandRouter::volumioGetVisibleSources Apr 30 19:50:28 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 30 19:50:28 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 30 19:50:28 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 30 19:50:28 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 30 19:50:28 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 30 19:50:28 volumio volumio[1009]: info: Received Get System Info Apr 30 19:50:28 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 30 19:50:28 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 30 19:50:28 volumio volumio[1009]: info: Discovery: Getting this device information Apr 30 19:50:28 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 30 19:50:28 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 30 19:50:28 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 30 19:50:28 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 30 19:50:28 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 30 19:50:28 volumio volumio[1009]: info: Listing playlists Apr 30 19:50:28 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 30 19:50:28 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 30 19:50:29 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 30 19:50:29 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 30 19:50:30 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 30 19:50:30 volumio volumio[1009]: info: Received Get System Info Apr 30 19:50:30 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 30 19:50:30 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 30 19:50:30 volumio volumio[1009]: info: Discovery: Getting this device information Apr 30 19:50:30 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 30 19:50:30 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 30 19:50:30 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 30 19:50:33 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 30 19:50:37 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 30 19:50:40 volumio ntpd[1792]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 30 19:50:52 volumio volumio-time-update[612]: volumio-time-update-util: Date not found in response Apr 30 19:50:52 volumio volumio-time-update[612]: volumio-time-update-util: Retrying in 5 seconds... Apr 30 19:50:57 volumio volumio-time-update[612]: volumio-time-update-util: Fetching time from Volumio... Apr 30 19:51:00 volumio kernel: EXT4-fs error (device mmcblk0p3): ext4_validate_block_bitmap:384: comm kworker/u8:2: bg 16: bad block bitmap checksum Apr 30 19:51:01 volumio volumio[1009]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=tinkerboard&variante=volumio&version=3.804&uuid=5378fd80de9ae9a702fa3f4c2ef55c7d" http://updates.volumio.org/downloader-v1/track-device Apr 30 19:51:01 volumio volumio[1009]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 30 19:51:01 volumio volumio[1009]: Dload Upload Total Spent Left Speed Apr 30 19:51:01 volumio volumio[1009]: [2.2K blob data] Apr 30 19:51:01 volumio volumio[1009]: retrying in 5 seconds, trial 1 Apr 30 19:51:01 volumio volumio[1009]: info: Volumio Calling Home Apr 30 19:51:03 volumio volumio[1009]: info: Discovery: adding b6e875b9-b78b-4fc1-b883-7112b1869358 Apr 30 19:51:03 volumio volumio[1009]: info: Discovery: Found device Volumio Apr 30 19:51:03 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 30 19:51:03 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 30 19:51:03 volumio volumio[1009]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 30 19:51:03 volumio volumio[1009]: Error: The value NaN is not a number Apr 30 19:51:03 volumio volumio[1009]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Apr 30 19:51:03 volumio volumio[1009]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Apr 30 19:51:03 volumio volumio[1009]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Apr 30 19:51:03 volumio volumio[1009]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Apr 30 19:51:03 volumio volumio[1009]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Apr 30 19:51:03 volumio volumio[1009]: at Browser.emit (events.js:400:28) Apr 30 19:51:03 volumio volumio[1009]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Apr 30 19:51:03 volumio volumio[1009]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Apr 30 19:51:03 volumio volumio[1009]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Apr 30 19:51:03 volumio volumio[1009]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Apr 30 19:51:03 volumio volumio[1009]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Apr 30 19:51:03 volumio volumio[1009]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Apr 30 19:51:03 volumio volumio[1009]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Apr 30 19:51:03 volumio volumio[1009]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 30 19:51:03 volumio sudo[2018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-30 19:50 Apr 30 19:51:03 volumio sudo[2018]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:53:51 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="53825f995260683aee752aed6b29f24e"