-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Fri 2025-09-05 10:38:16 CEST. -- Sep 05 10:37:00 volumio ntpd[721]: Soliciting pool server 45.140.164.53 Sep 05 10:37:00 volumio ntpd[721]: Soliciting pool server 91.224.149.41 Sep 05 10:37:00 volumio ntpd[721]: Soliciting pool server 164.132.166.29 Sep 05 10:37:00 volumio ntpd[721]: Soliciting pool server 45.132.96.81 Sep 05 10:37:00 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 10:37:00 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 10:37:00 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 05 10:37:01 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 05 10:37:01 volumio volumio-remote-updater[515]: No test mode Sep 05 10:37:01 volumio volumio-remote-updater[515]: No alpha test mode Sep 05 10:37:01 volumio volumio[948]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n\n

NEW ADDITIONS

\n\n","title":"Update v3.832","updateavailable":true} Sep 05 10:37:01 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 05 10:37:01 volumio ntpd[721]: Soliciting pool server 45.13.105.44 Sep 05 10:37:01 volumio ntpd[721]: Soliciting pool server 82.65.141.217 Sep 05 10:37:01 volumio ntpd[721]: Soliciting pool server 176.137.36.37 Sep 05 10:37:01 volumio ntpd[721]: Soliciting pool server 54.36.61.42 Sep 05 10:37:02 volumio ntpd[721]: Soliciting pool server 2001:41d0:a:1b13::137 Sep 05 10:37:02 volumio ntpd[721]: Soliciting pool server 5.39.80.51 Sep 05 10:37:05 volumio sudo[1514]: pam_unix(sudo:session): session closed for user root Sep 05 10:37:05 volumio volumio[948]: info: Cannot mount NAS internal_storage at system boot, trial number 4 ,retrying in 5 seconds Sep 05 10:37:05 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 05 10:37:05 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Sep 05 10:37:05 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 05 10:37:05 volumio volumio[948]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 05 10:37:05 volumio volumio[948]: info: CoreCommandRouter::volumioGetState Sep 05 10:37:05 volumio volumio[948]: info: CorePlayQueue::getTrack 0 Sep 05 10:37:06 volumio ntpd[721]: receive: Unexpected origin timestamp 0xec651fb1.ee174c13 does not match aorg 0000000000.00000000 from server@54.36.61.42 xmt 0xec651fb2.c65f1c6a Sep 05 10:37:06 volumio ntpd[721]: receive: Unexpected origin timestamp 0xec651fb1.ee28bbe5 does not match aorg 0000000000.00000000 from server@164.132.166.29 xmt 0xec651fb2.c68201d6 Sep 05 10:37:06 volumio ntpd[721]: receive: Unexpected origin timestamp 0xec651fb1.ee23cc46 does not match aorg 0000000000.00000000 from server@91.224.149.41 xmt 0xec651fb2.c734557c Sep 05 10:37:06 volumio ntpd[721]: receive: Unexpected origin timestamp 0xec651fb1.ee2d77e3 does not match aorg 0000000000.00000000 from server@37.59.63.125 xmt 0xec651fb2.c7061e2a Sep 05 10:37:06 volumio ntpd[721]: receive: Unexpected origin timestamp 0xec651fb1.ee3bcf29 does not match aorg 0000000000.00000000 from server@141.95.171.142 xmt 0xec651fb2.c6fe044c Sep 05 10:37:06 volumio ntpd[721]: receive: Unexpected origin timestamp 0xec651fb1.ee1e5dec does not match aorg 0000000000.00000000 from server@45.132.96.81 xmt 0xec651fb2.c6d53dca Sep 05 10:37:06 volumio ntpd[721]: receive: Unexpected origin timestamp 0xec651fb1.ee3203dd does not match aorg 0000000000.00000000 from server@51.68.44.27 xmt 0xec651fb2.c6ffde3c Sep 05 10:37:06 volumio ntpd[721]: receive: Unexpected origin timestamp 0xec651fb1.ee031f2b does not match aorg 0000000000.00000000 from server@82.65.141.217 xmt 0xec651fb2.c797c512 Sep 05 10:37:06 volumio ntpd[721]: receive: Unexpected origin timestamp 0xec651fb1.ee406a22 does not match aorg 0000000000.00000000 from server@82.65.248.56 xmt 0xec651fb2.c93de2d2 Sep 05 10:37:11 volumio sudo[1536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.1 //192.168.1.22/internal_storage /mnt/NAS/internal_storage Sep 05 10:37:11 volumio sudo[1536]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 10:37:11 volumio kernel: CIFS: Attempting to mount //192.168.1.22/internal_storage Sep 05 10:37:11 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 10:37:11 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 10:37:11 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 05 10:37:11 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 05 10:37:11 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 05 10:37:11 volumio volumio[948]: info: CoreCommandRouter::volumioGetBrowseSources Sep 05 10:37:11 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 10:37:14 volumio volumio[948]: error: MyVolumio Plugin failed to authenticate in a timely fashion Sep 05 10:37:14 volumio volumio[948]: info: Completed starting MyVolumio Plugin Sep 05 10:37:14 volumio volumio[948]: [Metrics] CommandRouter: 57s 771.46ms Sep 05 10:37:14 volumio volumio[948]: info: CoreCommandRouter::volumiosetStartupVolume Sep 05 10:37:14 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 10:37:14 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 10:37:14 volumio volumio[948]: info: CoreCommandRouter::Close All Modals sent Sep 05 10:37:14 volumio volumio[948]: info: CoreCommandRouter::Close All Modals sent Sep 05 10:37:15 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 05 10:37:15 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 05 10:37:15 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 05 10:37:17 volumio sudo[1536]: pam_unix(sudo:session): session closed for user root Sep 05 10:37:17 volumio volumio[948]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Sep 05 10:37:17 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 05 10:37:17 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Sep 05 10:37:21 volumio volumio[948]: info: BOOT COMPLETED Sep 05 10:38:15 volumio volumio[948]: info: CoreCommandRouter::volumioGetState Sep 05 10:38:15 volumio volumio[948]: info: CorePlayQueue::getTrack 0 Sep 05 10:38:15 volumio volumio[948]: info: [squeezelite_mc] 'play' status received while not being the current service. Sep 05 10:38:15 volumio volumio[948]: info: CoreCommandRouter::volumioGetState Sep 05 10:38:15 volumio volumio[948]: info: CorePlayQueue::getTrack 0 Sep 05 10:38:15 volumio volumio[948]: info: [squeezelite_mc] Stopping playback by current service... Sep 05 10:38:15 volumio volumio[948]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 10:38:15 volumio volumio[948]: info: CoreCommandRouter::volumioGetState Sep 05 10:38:15 volumio volumio[948]: info: CorePlayQueue::getTrack 0 Sep 05 10:38:15 volumio volumio[948]: info: CoreCommandRouter::volumioStop Sep 05 10:38:15 volumio volumio[948]: info: CoreStateMachine::stop Sep 05 10:38:15 volumio volumio[948]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 10:38:15 volumio volumio[948]: info: [squeezelite_mc] Setting ourselves as the current service... Sep 05 10:38:15 volumio volumio[948]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 10:38:15 volumio volumio[948]: info: [squeezelite_mc] #pushState(): {"status":"play","service":"squeezelite_mc","title":"AZIZA JALAL ❤️������ سيدة الطرب الأصيل عزيزة جلال ❤️������روحى فيك أنا","artist":"Aziza Jalal","album":"Pas d'album","albumart":"http://192.168.1.169:37119/?server_name=big-bear-lyrionmusicserver&url=http%3A%2F%2F192.168.1.189%3A9000%2Fmusic%2Fcurrent%2Fcover.jpg%3Fplayer%3D4c%253A0f%253Ac7%253Ad0%253Aac%253A14%26ms%3D1757061495550&fallback=http%3A%2F%2F192.168.1.169%2Falbumart","uri":"","trackType":"mp3","seek":0,"duration":1449,"samplerate":"191Kbps CBR","isStreaming":false,"volume":75,"repeat":false,"repeatSingle":false,"random":false} Sep 05 10:38:15 volumio volumio[948]: info: CoreCommandRouter::servicePushState Sep 05 10:38:15 volumio volumio[948]: info: CoreStateMachine::pushState Sep 05 10:38:15 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 10:38:15 volumio volumio[948]: info: CoreCommandRouter::volumioPushState Sep 05 10:38:15 volumio volumio[948]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 10:38:15 volumio volumio[948]: URIError: URI malformed Sep 05 10:38:15 volumio volumio[948]: at decodeURIComponent () Sep 05 10:38:15 volumio volumio[948]: at /data/plugins/music_service/squeezelite_mc/node_modules/lms-cli-notifications/dist/cjs/NotificationListener.js:188:39 Sep 05 10:38:15 volumio volumio[948]: at Array.map () Sep 05 10:38:15 volumio volumio[948]: at NotificationListener._NotificationListener_deduceNotification (/data/plugins/music_service/squeezelite_mc/node_modules/lms-cli-notifications/dist/cjs/NotificationListener.js:188:28) Sep 05 10:38:15 volumio volumio[948]: at NotificationListener._NotificationListener_handleIncomingData (/data/plugins/music_service/squeezelite_mc/node_modules/lms-cli-notifications/dist/cjs/NotificationListener.js:202:130) Sep 05 10:38:15 volumio volumio[948]: at Telnet.emit (events.js:327:22) Sep 05 10:38:15 volumio volumio[948]: at Socket. (/data/plugins/music_service/squeezelite_mc/node_modules/telnet-client/lib/index.js:144:26) Sep 05 10:38:15 volumio volumio[948]: at Socket.emit (events.js:315:20) Sep 05 10:38:15 volumio volumio[948]: at addChunk (internal/streams/readable.js:309:12) Sep 05 10:38:15 volumio volumio[948]: at readableAddChunk (internal/streams/readable.js:284:9) Sep 05 10:38:15 volumio volumio[948]: at Socket.Readable.push (internal/streams/readable.js:223:10) Sep 05 10:38:15 volumio volumio[948]: at TCP.onStreamRead (internal/stream_base_commons.js:188:23) Sep 05 10:38:15 volumio volumio[948]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 10:38:16 volumio sudo[1698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 10:37 Sep 05 10:38:16 volumio sudo[1698]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"