-- Logs begin at Mon 2024-04-01 08:54:34 UTC, end at Mon 2024-04-01 10:37:22 UTC. -- Apr 01 10:37:08 volumio ntpd[1015]: receive: Unexpected origin timestamp 0xe9b4f4de.5cddab3f does not match aorg 0000000000.00000000 from server@194.25.134.196 xmt 0xe9b50cd4.90bdccdf Apr 01 10:37:08 volumio ntpd[1015]: Soliciting pool server 144.76.76.107 Apr 01 10:37:08 volumio ntpd[1015]: receive: Unexpected origin timestamp 0xe9b4f4de.5cd3aa20 does not match aorg 0000000000.00000000 from server@116.202.171.176 xmt 0xe9b50cd4.918ac0f8 Apr 01 10:37:08 volumio volumio[965]: info: Discovery: A device disappeared from network Apr 01 10:37:08 volumio volumio[965]: info: Discovery: Device volumio disappeared from network Apr 01 10:37:08 volumio volumio[965]: info: Discovery: adding 28fd5904-b47f-497a-8a36-300dc254eaa0 Apr 01 10:37:08 volumio volumio[965]: info: Discovery: Found device volumio Apr 01 10:37:08 volumio volumio[965]: info: CoreCommandRouter::volumioGetState Apr 01 10:37:08 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Apr 01 10:37:08 volumio mpd[1278]: Apr 01 10:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 01 10:37:08 volumio systemd[1]: Started Music Player Daemon. Apr 01 10:37:08 volumio sudo[1255]: pam_unix(sudo:session): session closed for user root Apr 01 10:37:08 volumio sudo[1236]: pam_unix(sudo:session): session closed for user root Apr 01 10:37:08 volumio volumio[965]: info: Completed starting Core Plugins Apr 01 10:37:08 volumio volumio[965]: info: ------------------------------------------- Apr 01 10:37:08 volumio volumio[965]: info: ----- MyVolumio plugins startup ---- Apr 01 10:37:08 volumio volumio[965]: info: ------------------------------------------- Apr 01 10:37:08 volumio volumio[965]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 01 10:37:08 volumio volumio[965]: error: MPD error: The expression evaluated to a falsy value: Apr 01 10:37:08 volumio volumio[965]: assert.ok(self.idling) Apr 01 10:37:08 volumio volumio[965]: error: The expression evaluated to a falsy value: Apr 01 10:37:08 volumio volumio[965]: assert.ok(self.idling) Apr 01 10:37:08 volumio volumio[965]: info: MPD running with PID1278 Apr 01 10:37:08 volumio volumio[965]: ,establishing connection Apr 01 10:37:08 volumio volumio[965]: error: updateQueue error: null Apr 01 10:37:08 volumio volumio[965]: error: updateQueue error: null Apr 01 10:37:09 volumio ntpd[1015]: Soliciting pool server 144.76.18.245 Apr 01 10:37:12 volumio volumio[965]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-memorysave-touch Total Clients: 3 Apr 01 10:37:14 volumio sudo[1508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 01 10:37:14 volumio sudo[1512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 01 10:37:14 volumio sudo[1508]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 10:37:14 volumio sudo[1512]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 10:37:14 volumio sudo[1508]: pam_unix(sudo:session): session closed for user root Apr 01 10:37:14 volumio sudo[1514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 01 10:37:14 volumio sudo[1512]: pam_unix(sudo:session): session closed for user root Apr 01 10:37:14 volumio sudo[1514]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 10:37:14 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 01 10:37:14 volumio sudo[1514]: pam_unix(sudo:session): session closed for user root Apr 01 10:37:14 volumio volumio[965]: info: Upmpdcli Daemon Started Apr 01 10:37:14 volumio volumio[1534]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 01 10:37:15 volumio volumio[965]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-memorysave-touch Total Clients: 4 Apr 01 10:37:15 volumio volumio[965]: info: CoreCommandRouter::volumioGetState Apr 01 10:37:15 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Apr 01 10:37:15 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 01 10:37:15 volumio volumio[965]: info: Listing playlists Apr 01 10:37:15 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 01 10:37:15 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 01 10:37:15 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 01 10:37:15 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 01 10:37:16 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 01 10:37:16 volumio volumio[965]: info: VolumeController::SetAlsaVolume0 Apr 01 10:37:16 volumio volumio[965]: info: CoreStateMachine::pushState Apr 01 10:37:16 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Apr 01 10:37:16 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 01 10:37:16 volumio volumio[965]: info: CoreCommandRouter::volumioPushState Apr 01 10:37:16 volumio volumio[965]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 01 10:37:17 volumio volumio[965]: info: Adding plugin bluetooth to MyMusic Plugins Apr 01 10:37:17 volumio volumio[965]: info: Adding plugin multiroom to MyMusic Plugins Apr 01 10:37:17 volumio volumio[965]: info: Adding plugin metavolumio to MyMusic Plugins Apr 01 10:37:17 volumio volumio[965]: info: Adding plugin cd_controller to MyMusic Plugins Apr 01 10:37:17 volumio volumio[965]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 01 10:37:17 volumio volumio[965]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 01 10:37:17 volumio volumio[965]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 01 10:37:18 volumio volumio[965]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 01 10:37:18 volumio volumio[965]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 01 10:37:18 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 01 10:37:18 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 01 10:37:18 volumio volumio[965]: info: Starting MyVolumio Remote Streaming Endpoints Apr 01 10:37:18 volumio volumio[965]: info: MyVolumio login type: Token Apr 01 10:37:18 volumio volumio[965]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 01 10:37:18 volumio volumio[965]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 01 10:37:18 volumio volumio[965]: info: Starting Streaming Service Transparent Proxy Apr 01 10:37:18 volumio volumio[965]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 01 10:37:18 volumio volumio[965]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 01 10:37:18 volumio volumio[965]: info: Streaming services startup Apr 01 10:37:18 volumio volumio[965]: info: Starting Streaming Daemon Apr 01 10:37:18 volumio sudo[1577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 01 10:37:18 volumio volumio[965]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 01 10:37:18 volumio sudo[1577]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 01 10:37:18 volumio sudo[1577]: pam_unix(sudo:session): session closed for user root Apr 01 10:37:18 volumio volumio[965]: error: Cannot start Volumio Streaming Daemon Apr 01 10:37:18 volumio volumio[965]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 01 10:37:18 volumio volumio[965]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 01 10:37:19 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 01 10:37:19 volumio volumio[1534]: ....................................................................................++++ Apr 01 10:37:19 volumio volumio[965]: error: MyVolumio Custom Token format not valid, refreshing it Apr 01 10:37:19 volumio systemd[1]: systemd-fsckd.service: Succeeded. Apr 01 10:37:19 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 01 10:37:19 volumio volumio[965]: info: MyVolumio login type: Token Apr 01 10:37:19 volumio volumio[965]: STREAMING PROXY: Starting server on port 3245 Apr 01 10:37:19 volumio volumio[965]: Node JS runtime: 14 Apr 01 10:37:19 volumio sh[409]: timed out Apr 01 10:37:19 volumio dhcpcd[452]: timed out Apr 01 10:37:19 volumio sh[409]: dhcpcd exited Apr 01 10:37:19 volumio dhcpcd[452]: dhcpcd exited Apr 01 10:37:19 volumio sh[409]: ifup: failed to bring up eth0 Apr 01 10:37:19 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Apr 01 10:37:19 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Apr 01 10:37:19 volumio dhcpcd[1605]: sending commands to master dhcpcd process Apr 01 10:37:19 volumio ifplugd(eth0)[723]: client: sending commands to master dhcpcd process Apr 01 10:37:19 volumio dhcpcd[1016]: control command: /sbin/dhcpcd eth0 Apr 01 10:37:19 volumio ifplugd(eth0)[723]: Program executed successfully. Apr 01 10:37:19 volumio ifplugd(eth0)[723]: Link beat lost. Apr 01 10:37:19 volumio ifplugd[663]: Network Interface Plugging Daemon...start eth0...done. Apr 01 10:37:19 volumio systemd[1]: Started LSB: Brings up/down network automatically. Apr 01 10:37:19 volumio systemd[1]: Reached target Multi-User System. Apr 01 10:37:19 volumio systemd[1]: Reached target Graphical Interface. Apr 01 10:37:19 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 01 10:37:19 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 01 10:37:19 volumio volumio[965]: info: MyVolumio login type: Token Apr 01 10:37:19 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 01 10:37:19 volumio systemd[1]: Startup finished in 16.723s (kernel) + 32.516s (userspace) = 49.239s. Apr 01 10:37:20 volumio volumio[965]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 01 10:37:20 volumio volumio[1534]: ...............................++++ Apr 01 10:37:20 volumio volumio[1534]: e is 65537 (0x010001) Apr 01 10:37:20 volumio volumio[1534]: writing RSA key Apr 01 10:37:21 volumio volumio[965]: info: MyVolumio token set successfully Apr 01 10:37:21 volumio volumio[965]: info: MYVOLUMIO: Adding device Apr 01 10:37:21 volumio volumio[965]: info: MYVOLUMIO: Evaluating Server Apr 01 10:37:21 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Apr 01 10:37:21 volumio volumio[965]: info: MyVolumio Plan changed: superstar Apr 01 10:37:21 volumio volumio[965]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Apr 01 10:37:21 volumio volumio[965]: info: Adding audio output: Apr 01 10:37:21 volumio volumio[965]: info: MYVOLUMIO: Adding device Apr 01 10:37:21 volumio volumio[965]: info: MYVOLUMIO: Evaluating Server Apr 01 10:37:21 volumio volumio[965]: info: Remote config written successfully Apr 01 10:37:21 volumio volumio[965]: info: Starting Tunnel 1 Apr 01 10:37:21 volumio volumio[965]: info: Starting Tunnel Connection Checker Apr 01 10:37:21 volumio volumio[965]: info: Completed starting MyVolumio Plugin Apr 01 10:37:21 volumio volumio[965]: info: MYVolumio Device enabled Apr 01 10:37:21 volumio volumio[965]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 01 10:37:21 volumio volumio[965]: [2024-04-01T10:37:21.870Z] @firebase/database: FIREBASE WARNING: Exception was thrown by user callback. TypeError: Cannot read property 'replace' of null Apr 01 10:37:21 volumio volumio[965]: at MyVolumioPluginManager.fetchPluginKeys (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:8044) Apr 01 10:37:21 volumio volumio[965]: at MyVolumioPluginManager.loadMyVolumioPlugins (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:11563) Apr 01 10:37:21 volumio volumio[965]: at MyVolumioPluginManager.myVolumioDeviceStatusChanged (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:5527) Apr 01 10:37:21 volumio volumio[965]: at /myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:6807 Apr 01 10:37:21 volumio volumio[965]: at /volumio/node_modules/v-conf/index.js:165:13 Apr 01 10:37:21 volumio volumio[965]: at /volumio/node_modules/multimap/index.js:150:9 Apr 01 10:37:21 volumio volumio[965]: at Array.forEach () Apr 01 10:37:21 volumio volumio[965]: at /volumio/node_modules/multimap/index.js:149:13 Apr 01 10:37:21 volumio volumio[965]: at mapEach (/volumio/node_modules/multimap/index.js:9:5) Apr 01 10:37:21 volumio volumio[965]: at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5) Apr 01 10:37:21 volumio volumio[965]: at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10) Apr 01 10:37:21 volumio volumio[965]: at Config.set (/volumio/node_modules/v-conf/index.js:162:20) Apr 01 10:37:21 volumio volumio[965]: at myVolumio.togglePremiumPlugins (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:36225) Apr 01 10:37:21 volumio volumio[965]: at /myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:17857 Apr 01 10:37:21 volumio volumio[965]: at /volumio/node_modules/@firebase/database/dist/index.node.cjs.js:4549:22 Apr 01 10:37:21 volumio volumio[965]: at exceptionGuard (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:698:9) Apr 01 10:37:21 volumio volumio[965]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 01 10:37:21 volumio volumio[965]: TypeError: Cannot read property 'replace' of null Apr 01 10:37:21 volumio volumio[965]: at MyVolumioPluginManager.fetchPluginKeys (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:8044) Apr 01 10:37:21 volumio volumio[965]: at MyVolumioPluginManager.loadMyVolumioPlugins (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:11563) Apr 01 10:37:21 volumio volumio[965]: at MyVolumioPluginManager.myVolumioDeviceStatusChanged (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:5527) Apr 01 10:37:21 volumio volumio[965]: at /myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:6807 Apr 01 10:37:21 volumio volumio[965]: at /volumio/node_modules/v-conf/index.js:165:13 Apr 01 10:37:21 volumio volumio[965]: at /volumio/node_modules/multimap/index.js:150:9 Apr 01 10:37:21 volumio volumio[965]: at Array.forEach () Apr 01 10:37:21 volumio volumio[965]: at /volumio/node_modules/multimap/index.js:149:13 Apr 01 10:37:21 volumio volumio[965]: at mapEach (/volumio/node_modules/multimap/index.js:9:5) Apr 01 10:37:21 volumio volumio[965]: at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5) Apr 01 10:37:21 volumio volumio[965]: at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10) Apr 01 10:37:21 volumio volumio[965]: at Config.set (/volumio/node_modules/v-conf/index.js:162:20) Apr 01 10:37:21 volumio volumio[965]: at myVolumio.togglePremiumPlugins (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:36225) Apr 01 10:37:21 volumio volumio[965]: at /myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:17857 Apr 01 10:37:21 volumio volumio[965]: at /volumio/node_modules/@firebase/database/dist/index.node.cjs.js:4549:22 Apr 01 10:37:21 volumio volumio[965]: at exceptionGuard (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:698:9) Apr 01 10:37:21 volumio volumio[965]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 01 10:37:22 volumio sudo[1711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-01 10:36 Apr 01 10:37:22 volumio sudo[1711]: 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/"