-- Logs begin at Tue 2025-04-22 09:06:57 +05, end at Tue 2025-04-22 09:10:54 +05. -- Apr 22 09:09:04 volumio volumio[1131]: info: Discovery: adding 486817eb-8fb8-42a6-9675-373c2faf802e Apr 22 09:09:04 volumio volumio[1131]: info: Discovery: Found device Volumio Apr 22 09:09:04 volumio volumio[1131]: info: CoreCommandRouter::volumioGetState Apr 22 09:09:04 volumio volumio[1131]: info: CorePlayQueue::getTrack 0 Apr 22 09:09:07 volumio volumio[1131]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Apr 22 09:09:07 volumio volumio[1131]: LoungeScreenDisconnected { Apr 22 09:09:07 volumio volumio[1131]: AID: null, Apr 22 09:09:07 volumio volumio[1131]: name: 'loungeScreenDisconnected', Apr 22 09:09:07 volumio volumio[1131]: payload: {} Apr 22 09:09:07 volumio volumio[1131]: } Apr 22 09:09:07 volumio volumio[1131]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 22 09:09:07 volumio volumio[1131]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 22 09:09:07 volumio volumio[1131]: Stack trace: Apr 22 09:09:07 volumio volumio[1131]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 22 09:09:07 volumio volumio[1131]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 22 09:09:07 volumio volumio[1131]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 22 09:09:07 volumio volumio[1131]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 22 09:09:07 volumio volumio[1131]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 22 09:09:07 volumio volumio[1131]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Apr 22 09:09:07 volumio volumio[1131]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Apr 22 09:09:07 volumio volumio[1131]: at new Promise () Apr 22 09:09:07 volumio volumio[1131]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Apr 22 09:09:07 volumio volumio[1131]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Apr 22 09:09:07 volumio volumio[1131]: at Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:207:26) Apr 22 09:09:07 volumio volumio[1131]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Apr 22 09:09:07 volumio volumio[1131]: at async Promise.all (index 0) Apr 22 09:09:07 volumio volumio[1131]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Apr 22 09:09:07 volumio volumio[1131]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 22 09:09:07 volumio volumio[1131]: error: [yt-cast-receiver] Failed to start receiver: Apr 22 09:09:07 volumio volumio[1131]: (AppError) Failed to start YouTubeApp Apr 22 09:09:07 volumio volumio[1131]: --->(SessionError) (YouTube) Failed to establish session Apr 22 09:09:07 volumio volumio[1131]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Apr 22 09:09:07 volumio volumio[1131]: Error info: { Apr 22 09:09:07 volumio volumio[1131]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Apr 22 09:09:07 volumio volumio[1131]: } Apr 22 09:09:07 volumio volumio[1131]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo ENOTFOUND www.youtube.com Apr 22 09:09:07 volumio volumio[1131]: Stack trace: Apr 22 09:09:07 volumio volumio[1131]: AppError: Failed to start YouTubeApp Apr 22 09:09:07 volumio volumio[1131]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Apr 22 09:09:07 volumio volumio[1131]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Apr 22 09:09:07 volumio volumio[1131]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 22 09:09:07 volumio volumio[1131]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Apr 22 09:09:07 volumio volumio[1131]: LoungeScreenDisconnected { Apr 22 09:09:07 volumio volumio[1131]: AID: null, Apr 22 09:09:07 volumio volumio[1131]: name: 'loungeScreenDisconnected', Apr 22 09:09:07 volumio volumio[1131]: payload: {} Apr 22 09:09:07 volumio volumio[1131]: } Apr 22 09:09:07 volumio volumio[1131]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 22 09:09:07 volumio volumio[1131]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 22 09:09:07 volumio volumio[1131]: Stack trace: Apr 22 09:09:07 volumio volumio[1131]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 22 09:09:07 volumio volumio[1131]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 22 09:09:07 volumio volumio[1131]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 22 09:09:07 volumio volumio[1131]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 22 09:09:07 volumio volumio[1131]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 22 09:09:07 volumio volumio[1131]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Apr 22 09:09:07 volumio volumio[1131]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Apr 22 09:09:07 volumio volumio[1131]: at new Promise () Apr 22 09:09:07 volumio volumio[1131]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Apr 22 09:09:07 volumio volumio[1131]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Apr 22 09:09:07 volumio volumio[1131]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:35 Apr 22 09:09:07 volumio volumio[1131]: at Array.forEach () Apr 22 09:09:07 volumio volumio[1131]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:114:24) Apr 22 09:09:07 volumio volumio[1131]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Apr 22 09:09:07 volumio volumio[1131]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 22 09:09:07 volumio volumio[1131]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Apr 22 09:09:07 volumio volumio[1131]: LoungeScreenDisconnected { Apr 22 09:09:07 volumio volumio[1131]: AID: null, Apr 22 09:09:07 volumio volumio[1131]: name: 'loungeScreenDisconnected', Apr 22 09:09:07 volumio volumio[1131]: payload: {} Apr 22 09:09:07 volumio volumio[1131]: } Apr 22 09:09:07 volumio volumio[1131]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 22 09:09:07 volumio volumio[1131]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 22 09:09:07 volumio volumio[1131]: Stack trace: Apr 22 09:09:07 volumio volumio[1131]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 22 09:09:07 volumio volumio[1131]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 22 09:09:07 volumio volumio[1131]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 22 09:09:07 volumio volumio[1131]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 22 09:09:07 volumio volumio[1131]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 22 09:09:07 volumio volumio[1131]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Apr 22 09:09:07 volumio volumio[1131]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Apr 22 09:09:07 volumio volumio[1131]: error: [ytcr] Failed to start plugin: Apr 22 09:09:07 volumio volumio[1131]: (AppError) Failed to start YouTubeApp Apr 22 09:09:07 volumio volumio[1131]: --->(SessionError) (YouTube) Failed to establish session Apr 22 09:09:07 volumio volumio[1131]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Apr 22 09:09:07 volumio volumio[1131]: Error info: { Apr 22 09:09:07 volumio volumio[1131]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Apr 22 09:09:07 volumio volumio[1131]: } Apr 22 09:09:07 volumio volumio[1131]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo ENOTFOUND www.youtube.com Apr 22 09:09:07 volumio volumio[1131]: Stack trace: Apr 22 09:09:07 volumio volumio[1131]: AppError: Failed to start YouTubeApp Apr 22 09:09:07 volumio volumio[1131]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Apr 22 09:09:07 volumio volumio[1131]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Apr 22 09:09:07 volumio volumio[1131]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 22 09:09:08 volumio volumio-time-update[672]: volumio-time-update-util: Date not found in response Apr 22 09:09:08 volumio volumio-time-update[672]: volumio-time-update-util: Retrying in 5 seconds... Apr 22 09:09:13 volumio volumio-time-update[672]: volumio-time-update-util: Fetching time from Volumio... Apr 22 09:09:16 volumio volumio[1131]: error: MyVolumio Plugin failed to start in a timely fashion Apr 22 09:09:16 volumio volumio[1131]: [Metrics] CommandRouter: 88s 287.73ms Apr 22 09:09:16 volumio volumio[1131]: info: CoreCommandRouter::volumiosetStartupVolume Apr 22 09:09:16 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 09:09:16 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 09:09:16 volumio volumio[1131]: info: CoreCommandRouter::Close All Modals sent Apr 22 09:09:16 volumio volumio[1131]: info: CoreCommandRouter::Close All Modals sent Apr 22 09:09:17 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 22 09:09:17 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 22 09:09:17 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 22 09:09:17 volumio ntpd[856]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 22 09:09:23 volumio volumio[1131]: info: BOOT COMPLETED Apr 22 09:09:23 volumio volumio[1131]: info: mpdhttpout ---Boot completed detected! Patching mpd now! Apr 22 09:09:23 volumio volumio[1131]: info: CoreCommandRouter::volumioGetState Apr 22 09:09:23 volumio volumio[1131]: info: CorePlayQueue::getTrack 0 Apr 22 09:09:23 volumio volumio[1131]: info: CoreCommandRouter::volumioPause Apr 22 09:09:23 volumio volumio[1131]: info: CoreStateMachine::pause Apr 22 09:09:23 volumio volumio[1131]: info: mpdhttpout --- Volumio set on pause Apr 22 09:09:23 volumio volumio[1131]: info: mpdhttpout ---Configuration successfully added to mpd.conf. Apr 22 09:09:24 volumio volumio[1131]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Apr 22 09:09:24 volumio volumio[1131]: LoungeScreenDisconnected { Apr 22 09:09:24 volumio volumio[1131]: AID: null, Apr 22 09:09:24 volumio volumio[1131]: name: 'loungeScreenDisconnected', Apr 22 09:09:24 volumio volumio[1131]: payload: {} Apr 22 09:09:24 volumio volumio[1131]: } Apr 22 09:09:24 volumio volumio[1131]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 22 09:09:24 volumio volumio[1131]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 22 09:09:24 volumio volumio[1131]: Stack trace: Apr 22 09:09:24 volumio volumio[1131]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 22 09:09:24 volumio volumio[1131]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 22 09:09:24 volumio volumio[1131]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 22 09:09:24 volumio volumio[1131]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 22 09:09:24 volumio volumio[1131]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 22 09:09:24 volumio volumio[1131]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Apr 22 09:09:24 volumio volumio[1131]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Apr 22 09:09:24 volumio volumio[1131]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 Apr 22 09:09:25 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Apr 22 09:09:25 volumio sudo[1743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 22 09:09:25 volumio sudo[1743]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 09:09:25 volumio systemd[1]: Stopping Music Player Daemon... Apr 22 09:09:25 volumio systemd[1]: mpd.service: Succeeded. Apr 22 09:09:25 volumio systemd[1]: Stopped Music Player Daemon. Apr 22 09:09:26 volumio systemd[1]: Starting Music Player Daemon... Apr 22 09:09:26 volumio sudo[1745]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 22 09:09:26 volumio sudo[1745]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 09:09:26 volumio sudo[1745]: pam_unix(sudo:session): session closed for user root Apr 22 09:09:26 volumio volumio[1131]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.799&uuid=fb79d776a0495ebfe08cf89991737bed" http://updates.volumio.org/downloader-v1/track-device Apr 22 09:09:26 volumio volumio[1131]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 22 09:09:26 volumio volumio[1131]: Dload Upload Total Spent Left Speed Apr 22 09:09:26 volumio volumio[1131]: [1.6K blob data] Apr 22 09:09:26 volumio volumio[1131]: retrying in 5 seconds, trial 1 Apr 22 09:09:26 volumio volumio[1131]: info: Volumio Calling Home Apr 22 09:09:28 volumio mpd[1747]: Apr 22 09:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 22 09:09:28 volumio systemd[1]: Started Music Player Daemon. Apr 22 09:09:28 volumio sudo[1743]: pam_unix(sudo:session): session closed for user root Apr 22 09:09:28 volumio volumio[1131]: error: updateQueue error: null Apr 22 09:09:33 volumio volumio-time-update[672]: volumio-time-update-util: Date not found in response Apr 22 09:09:33 volumio volumio-time-update[672]: volumio-time-update-util: Retrying in 5 seconds... Apr 22 09:09:37 volumio ntpd[856]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 22 09:09:38 volumio volumio-time-update[672]: volumio-time-update-util: Fetching time from Volumio... Apr 22 09:09:42 volumio dhcpcd[779]: eth0: carrier acquired Apr 22 09:09:42 volumio kernel: atl1c 0000:01:00.0: atl1c: eth0 NIC Link is Up<100 Mbps Full Duplex> Apr 22 09:09:42 volumio dhcpcd[779]: eth0: IAID 1d:75:55:be Apr 22 09:09:42 volumio dhcpcd[779]: eth0: soliciting an IPv6 router Apr 22 09:09:42 volumio dhcpcd[779]: eth0: soliciting a DHCP lease Apr 22 09:09:42 volumio ifplugd(eth0)[851]: Link beat detected. Apr 22 09:09:43 volumio ifplugd(eth0)[851]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Apr 22 09:09:43 volumio dhcpcd[1795]: sending commands to master dhcpcd process Apr 22 09:09:43 volumio ifplugd(eth0)[851]: client: sending commands to master dhcpcd process Apr 22 09:09:43 volumio dhcpcd[779]: control command: /sbin/dhcpcd eth0 Apr 22 09:09:43 volumio ifplugd(eth0)[851]: Program executed successfully. Apr 22 09:09:43 volumio kernel: atl1c 0000:01:00.0: atl1c: eth0 NIC Link is Down Apr 22 09:09:43 volumio dhcpcd[779]: eth0: carrier lost Apr 22 09:09:44 volumio volumio[1131]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 22 09:09:44 volumio ifplugd(eth0)[851]: Link beat lost. Apr 22 09:09:45 volumio dhcpcd[779]: eth0: carrier acquired Apr 22 09:09:45 volumio kernel: atl1c 0000:01:00.0: atl1c: eth0 NIC Link is Up<100 Mbps Full Duplex> Apr 22 09:09:45 volumio dhcpcd[779]: eth0: IAID 1d:75:55:be Apr 22 09:09:45 volumio ifplugd(eth0)[851]: Link beat detected. Apr 22 09:09:45 volumio dhcpcd[779]: eth0: soliciting a DHCP lease Apr 22 09:09:46 volumio dhcpcd[779]: eth0: soliciting an IPv6 router Apr 22 09:09:50 volumio dhcpcd[779]: eth0: probing for an IPv4LL address Apr 22 09:09:55 volumio dhcpcd[779]: eth0: using IPv4LL address 169.254.97.9 Apr 22 09:09:55 volumio dhcpcd[779]: eth0: adding route to 169.254.0.0/16 Apr 22 09:09:55 volumio dhcpcd[779]: eth0: adding default route Apr 22 09:09:55 volumio dhcpcd[779]: wlan0: deleting default route Apr 22 09:09:55 volumio avahi-daemon[691]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.97.9. Apr 22 09:09:55 volumio avahi-daemon[691]: New relevant interface eth0.IPv4 for mDNS. Apr 22 09:09:55 volumio avahi-daemon[691]: Registering new address record for 169.254.97.9 on eth0.IPv4. Apr 22 09:09:56 volumio volumio[1131]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.799&uuid=fb79d776a0495ebfe08cf89991737bed" http://updates.volumio.org/downloader-v1/track-device Apr 22 09:09:56 volumio volumio[1131]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 22 09:09:56 volumio volumio[1131]: Dload Upload Total Spent Left Speed Apr 22 09:09:56 volumio volumio[1131]: [1.6K blob data] Apr 22 09:09:56 volumio volumio[1131]: retrying in 5 seconds, trial 2 Apr 22 09:09:56 volumio volumio[1131]: info: Volumio Calling Home Apr 22 09:09:56 volumio ntpd[856]: Listen normally on 5 eth0 169.254.97.9:123 Apr 22 09:09:56 volumio ntpd[856]: new interface(s) found: waking up resolver Apr 22 09:09:56 volumio kernel: atl1c 0000:01:00.0: atl1c: eth0 NIC Link is Down Apr 22 09:09:56 volumio dhcpcd[779]: eth0: carrier lost Apr 22 09:09:56 volumio avahi-daemon[691]: Withdrawing address record for 169.254.97.9 on eth0. Apr 22 09:09:56 volumio avahi-daemon[691]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.97.9. Apr 22 09:09:56 volumio dhcpcd[779]: wlan0: adding default route Apr 22 09:09:56 volumio dhcpcd[779]: eth0: deleting route to 169.254.0.0/16 Apr 22 09:09:56 volumio dhcpcd[779]: eth0: deleting default route Apr 22 09:09:56 volumio avahi-daemon[691]: Interface eth0.IPv4 no longer relevant for mDNS. Apr 22 09:09:56 volumio volumio[1131]: info: Discovery: A device disappeared from network Apr 22 09:09:56 volumio volumio[1131]: info: Discovery: Device volumio disappeared from network Apr 22 09:09:57 volumio ifplugd(eth0)[851]: Link beat lost. Apr 22 09:09:57 volumio ntpd[856]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 22 09:09:58 volumio dhcpcd[779]: eth0: carrier acquired Apr 22 09:09:58 volumio kernel: atl1c 0000:01:00.0: atl1c: eth0 NIC Link is Up<100 Mbps Full Duplex> Apr 22 09:09:58 volumio dhcpcd[779]: eth0: IAID 1d:75:55:be Apr 22 09:09:58 volumio dhcpcd[779]: eth0: soliciting an IPv6 router Apr 22 09:09:58 volumio ifplugd(eth0)[851]: Link beat detected. Apr 22 09:09:58 volumio volumio-time-update[672]: volumio-time-update-util: Date not found in response Apr 22 09:09:58 volumio volumio-time-update[672]: volumio-time-update-util: Retrying in 5 seconds... Apr 22 09:09:59 volumio dhcpcd[779]: eth0: soliciting a DHCP lease Apr 22 09:09:59 volumio ntpd[856]: Deleting interface #5 eth0, 169.254.97.9#123, interface stats: received=0, sent=0, dropped=0, active_time=3 secs Apr 22 09:10:00 volumio kernel: atl1c 0000:01:00.0: atl1c: eth0 NIC Link is Down Apr 22 09:10:00 volumio dhcpcd[779]: eth0: carrier lost Apr 22 09:10:00 volumio ifplugd(eth0)[851]: Link beat lost. Apr 22 09:10:01 volumio dhcpcd[779]: eth0: carrier acquired Apr 22 09:10:01 volumio kernel: atl1c 0000:01:00.0: atl1c: eth0 NIC Link is Up<100 Mbps Full Duplex> Apr 22 09:10:01 volumio dhcpcd[779]: eth0: IAID 1d:75:55:be Apr 22 09:10:01 volumio dhcpcd[779]: eth0: soliciting a DHCP lease Apr 22 09:10:01 volumio ifplugd(eth0)[851]: Link beat detected. Apr 22 09:10:02 volumio dhcpcd[779]: eth0: soliciting an IPv6 router Apr 22 09:10:03 volumio volumio-time-update[672]: volumio-time-update-util: Fetching time from Volumio... Apr 22 09:10:05 volumio dhcpcd[779]: eth0: offered 192.168.0.115 from 192.168.0.1 Apr 22 09:10:05 volumio dhcpcd[779]: eth0: probing address 192.168.0.115/24 Apr 22 09:10:06 volumio dhcpcd[779]: eth0: carrier lost Apr 22 09:10:06 volumio kernel: atl1c 0000:01:00.0: atl1c: eth0 NIC Link is Down Apr 22 09:10:07 volumio ifplugd(eth0)[851]: Link beat lost. Apr 22 09:10:07 volumio volumio[1131]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Apr 22 09:10:07 volumio volumio[1131]: LoungeScreenDisconnected { Apr 22 09:10:07 volumio volumio[1131]: AID: null, Apr 22 09:10:07 volumio volumio[1131]: name: 'loungeScreenDisconnected', Apr 22 09:10:07 volumio volumio[1131]: payload: {} Apr 22 09:10:07 volumio volumio[1131]: } Apr 22 09:10:07 volumio volumio[1131]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 22 09:10:07 volumio volumio[1131]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 22 09:10:07 volumio volumio[1131]: Stack trace: Apr 22 09:10:07 volumio volumio[1131]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 22 09:10:07 volumio volumio[1131]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 22 09:10:07 volumio volumio[1131]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 22 09:10:07 volumio volumio[1131]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 22 09:10:07 volumio volumio[1131]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 22 09:10:07 volumio volumio[1131]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Apr 22 09:10:07 volumio volumio[1131]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Apr 22 09:10:07 volumio volumio[1131]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 Apr 22 09:10:07 volumio volumio[1131]: (node:1131) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Apr 22 09:10:07 volumio volumio[1131]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Apr 22 09:10:07 volumio volumio[1131]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Apr 22 09:10:07 volumio volumio[1131]: (Use `node --trace-warnings ...` to show where the warning was created) Apr 22 09:10:07 volumio volumio[1131]: (node:1131) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 7) Apr 22 09:10:07 volumio volumio[1131]: (node:1131) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Apr 22 09:10:13 volumio dhcpcd[779]: eth0: carrier acquired Apr 22 09:10:13 volumio kernel: atl1c 0000:01:00.0: atl1c: eth0 NIC Link is Up<100 Mbps Full Duplex> Apr 22 09:10:13 volumio dhcpcd[779]: eth0: IAID 1d:75:55:be Apr 22 09:10:13 volumio dhcpcd[779]: eth0: soliciting a DHCP lease Apr 22 09:10:14 volumio ifplugd(eth0)[851]: Link beat detected. Apr 22 09:10:14 volumio dhcpcd[779]: eth0: soliciting an IPv6 router Apr 22 09:10:16 volumio dhcpcd[779]: eth0: offered 192.168.0.115 from 192.168.0.1 Apr 22 09:10:16 volumio dhcpcd[779]: eth0: probing address 192.168.0.115/24 Apr 22 09:10:17 volumio ntpd[856]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 22 09:10:19 volumio kernel: atl1c 0000:01:00.0: atl1c: eth0 NIC Link is Down Apr 22 09:10:19 volumio dhcpcd[779]: eth0: carrier lost Apr 22 09:10:20 volumio ifplugd(eth0)[851]: Link beat lost. Apr 22 09:10:23 volumio volumio-time-update[672]: volumio-time-update-util: Date not found in response Apr 22 09:10:23 volumio volumio-time-update[672]: volumio-time-update-util: Retrying in 5 seconds... Apr 22 09:10:24 volumio volumio[1131]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Apr 22 09:10:24 volumio volumio[1131]: LoungeScreenDisconnected { Apr 22 09:10:24 volumio volumio[1131]: AID: null, Apr 22 09:10:24 volumio volumio[1131]: name: 'loungeScreenDisconnected', Apr 22 09:10:24 volumio volumio[1131]: payload: {} Apr 22 09:10:24 volumio volumio[1131]: } Apr 22 09:10:24 volumio volumio[1131]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 22 09:10:24 volumio volumio[1131]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 22 09:10:24 volumio volumio[1131]: Stack trace: Apr 22 09:10:24 volumio volumio[1131]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 22 09:10:24 volumio volumio[1131]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 22 09:10:24 volumio volumio[1131]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 22 09:10:24 volumio volumio[1131]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 22 09:10:24 volumio volumio[1131]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 22 09:10:24 volumio volumio[1131]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Apr 22 09:10:24 volumio volumio[1131]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Apr 22 09:10:24 volumio volumio[1131]: (node:1131) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Apr 22 09:10:24 volumio volumio[1131]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Apr 22 09:10:24 volumio volumio[1131]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Apr 22 09:10:24 volumio volumio[1131]: (node:1131) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 9) Apr 22 09:10:26 volumio dhcpcd[779]: eth0: carrier acquired Apr 22 09:10:26 volumio kernel: atl1c 0000:01:00.0: atl1c: eth0 NIC Link is Up<100 Mbps Full Duplex> Apr 22 09:10:26 volumio dhcpcd[779]: eth0: IAID 1d:75:55:be Apr 22 09:10:26 volumio dhcpcd[779]: eth0: soliciting an IPv6 router Apr 22 09:10:26 volumio ifplugd(eth0)[851]: Link beat detected. Apr 22 09:10:26 volumio dhcpcd[779]: eth0: soliciting a DHCP lease Apr 22 09:10:27 volumio volumio[1131]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Apr 22 09:10:27 volumio volumio[1131]: LoungeScreenDisconnected { Apr 22 09:10:27 volumio volumio[1131]: AID: null, Apr 22 09:10:27 volumio volumio[1131]: name: 'loungeScreenDisconnected', Apr 22 09:10:27 volumio volumio[1131]: payload: {} Apr 22 09:10:27 volumio volumio[1131]: } Apr 22 09:10:27 volumio volumio[1131]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 22 09:10:27 volumio volumio[1131]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 22 09:10:27 volumio volumio[1131]: Stack trace: Apr 22 09:10:27 volumio volumio[1131]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 22 09:10:27 volumio volumio[1131]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 22 09:10:27 volumio volumio[1131]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 22 09:10:27 volumio volumio[1131]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 22 09:10:27 volumio volumio[1131]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 22 09:10:27 volumio volumio[1131]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Apr 22 09:10:27 volumio volumio[1131]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Apr 22 09:10:27 volumio volumio[1131]: (node:1131) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Apr 22 09:10:27 volumio volumio[1131]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Apr 22 09:10:27 volumio volumio[1131]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Apr 22 09:10:27 volumio volumio[1131]: (node:1131) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 11) Apr 22 09:10:28 volumio volumio-time-update[672]: volumio-time-update-util: Fetching time from Volumio... Apr 22 09:10:29 volumio dhcpcd[779]: eth0: offered 192.168.0.115 from 192.168.0.1 Apr 22 09:10:29 volumio dhcpcd[779]: eth0: probing address 192.168.0.115/24 Apr 22 09:10:35 volumio dhcpcd[779]: eth0: leased 192.168.0.115 for 86400 seconds Apr 22 09:10:35 volumio avahi-daemon[691]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.115. Apr 22 09:10:35 volumio dhcpcd[779]: eth0: adding route to 192.168.0.0/24 Apr 22 09:10:35 volumio avahi-daemon[691]: New relevant interface eth0.IPv4 for mDNS. Apr 22 09:10:35 volumio avahi-daemon[691]: Registering new address record for 192.168.0.115 on eth0.IPv4. Apr 22 09:10:35 volumio dhcpcd[779]: eth0: adding default route via 192.168.0.1 Apr 22 09:10:35 volumio dhcpcd[779]: wlan0: deleting default route Apr 22 09:10:35 volumio dnsmasq[1155]: reading /etc/resolv.conf Apr 22 09:10:35 volumio dnsmasq[1155]: using nameserver 1.1.1.1#53 Apr 22 09:10:35 volumio dnsmasq[1155]: using nameserver 208.67.222.222#53 Apr 22 09:10:35 volumio dnsmasq[1155]: using nameserver 208.67.220.220#53 Apr 22 09:10:35 volumio ntpd[856]: ntpd exiting on signal 15 (Terminated) Apr 22 09:10:35 volumio systemd[1]: Stopping Network Time Service... Apr 22 09:10:35 volumio systemd[1]: ntp.service: Succeeded. Apr 22 09:10:35 volumio systemd[1]: Stopped Network Time Service. Apr 22 09:10:35 volumio systemd[1]: Starting Network Time Service... Apr 22 09:10:35 volumio ntpd[2092]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 22 09:10:35 volumio ntpd[2092]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Apr 22 09:10:35 volumio systemd[1]: Started Network Time Service. Apr 22 09:10:35 volumio ntpd[2098]: proto: precision = 2.304 usec (-19) Apr 22 09:10:35 volumio ntpd[2098]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 22 09:10:35 volumio ntpd[2098]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 22 09:10:35 volumio ntpd[2098]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 847 days ago Apr 22 09:10:35 volumio ntpd[2098]: Listen and drop on 0 v6wildcard [::]:123 Apr 22 09:10:35 volumio ntpd[2098]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 22 09:10:35 volumio ntpd[2098]: Listen normally on 2 lo 127.0.0.1:123 Apr 22 09:10:35 volumio ntpd[2098]: Listen normally on 3 eth0 192.168.0.115:123 Apr 22 09:10:35 volumio ntpd[2098]: Listen normally on 4 wlan0 192.168.211.1:123 Apr 22 09:10:35 volumio ntpd[2098]: Listen normally on 5 wlan0 169.254.37.150:123 Apr 22 09:10:35 volumio ntpd[2098]: Listening on routing socket on fd #22 for interface updates Apr 22 09:10:35 volumio ntpd[2098]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 22 09:10:35 volumio ntpd[2098]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 22 09:10:36 volumio ntpd[2098]: Soliciting pool server 92.255.126.12 Apr 22 09:10:37 volumio ntpd[2098]: Soliciting pool server 178.217.98.201 Apr 22 09:10:37 volumio ntpd[2098]: Soliciting pool server 89.169.135.41 Apr 22 09:10:38 volumio ntpd[2098]: Soliciting pool server 82.142.168.18 Apr 22 09:10:38 volumio ntpd[2098]: Soliciting pool server 147.45.251.137 Apr 22 09:10:38 volumio ntpd[2098]: Soliciting pool server 94.141.168.72 Apr 22 09:10:39 volumio ntpd[2098]: Soliciting pool server 79.164.218.223 Apr 22 09:10:39 volumio ntpd[2098]: Soliciting pool server 77.37.240.196 Apr 22 09:10:39 volumio ntpd[2098]: Soliciting pool server 151.0.2.53 Apr 22 09:10:39 volumio ntpd[2098]: Soliciting pool server 51.250.110.169 Apr 22 09:10:40 volumio ntpd[2098]: Soliciting pool server 89.179.240.219 Apr 22 09:10:40 volumio ntpd[2098]: Soliciting pool server 94.228.114.152 Apr 22 09:10:40 volumio ntpd[2098]: Soliciting pool server 83.143.51.50 Apr 22 09:10:40 volumio ntpd[2098]: Soliciting pool server 176.123.165.98 Apr 22 09:10:41 volumio ntpd[2098]: Soliciting pool server 217.69.139.133 Apr 22 09:10:41 volumio ntpd[2098]: Soliciting pool server 212.113.99.6 Apr 22 09:10:41 volumio ntpd[2098]: Soliciting pool server 162.159.200.123 Apr 22 09:10:41 volumio ntpd[2098]: Soliciting pool server 93.95.98.77 Apr 22 09:10:43 volumio volumio[1131]: info: Volumio Network Manager: Network status updated: 1 Apr 22 09:10:44 volumio volumio[1131]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 22 09:10:44 volumio volumio[1131]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 22 09:10:44 volumio volumio[1131]: info: Discovery: adding 486817eb-8fb8-42a6-9675-373c2faf802e Apr 22 09:10:44 volumio volumio[1131]: info: Discovery: Found device Volumio Apr 22 09:10:44 volumio volumio[1131]: info: CoreCommandRouter::volumioGetState Apr 22 09:10:44 volumio volumio[1131]: info: CorePlayQueue::getTrack 0 Apr 22 09:10:45 volumio volumio[1131]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Apr 22 09:10:45 volumio volumio[1131]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 22 09:10:45 volumio volumio[1131]: info: Discovery: this is already registered, 486817eb-8fb8-42a6-9675-373c2faf802e Apr 22 09:10:45 volumio volumio[1131]: info: Discovery: Found device Volumio Apr 22 09:10:45 volumio volumio[1131]: info: CoreCommandRouter::volumioGetState Apr 22 09:10:45 volumio volumio[1131]: info: CorePlayQueue::getTrack 0 Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 22 09:10:46 volumio volumio[1131]: info: Adding plugin bluetooth to MyMusic Plugins Apr 22 09:10:46 volumio volumio[1131]: info: Adding plugin multiroom to MyMusic Plugins Apr 22 09:10:46 volumio volumio[1131]: info: Adding plugin metavolumio to MyMusic Plugins Apr 22 09:10:46 volumio volumio[1131]: info: Adding plugin cd_controller to MyMusic Plugins Apr 22 09:10:46 volumio volumio[1131]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 22 09:10:46 volumio volumio[1131]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 22 09:10:46 volumio volumio[1131]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 22 09:10:48 volumio volumio[1131]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 22 09:10:48 volumio volumio[1131]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 22 09:10:48 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 09:10:48 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 09:10:48 volumio volumio[1131]: info: Starting MyVolumio Remote Streaming Endpoints Apr 22 09:10:48 volumio volumio[1131]: info: MyVolumio login type: Token Apr 22 09:10:48 volumio volumio[1131]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 22 09:10:48 volumio volumio[1131]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 22 09:10:48 volumio volumio-time-update[672]: volumio-time-update-util: Date not found in response Apr 22 09:10:48 volumio volumio-time-update[672]: volumio-time-update-util: Retrying in 5 seconds... Apr 22 09:10:50 volumio volumio[1131]: info: Starting Streaming Service Transparent Proxy Apr 22 09:10:50 volumio volumio[1131]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 22 09:10:50 volumio volumio[1131]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 22 09:10:50 volumio volumio[1131]: info: Streaming services startup Apr 22 09:10:50 volumio volumio[1131]: info: Starting Streaming Daemon Apr 22 09:10:50 volumio sudo[2127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 22 09:10:50 volumio sudo[2127]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 09:10:50 volumio sudo[2127]: pam_unix(sudo:session): session closed for user root Apr 22 09:10:50 volumio volumio[1131]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 22 09:10:50 volumio volumio[1131]: error: Cannot start Volumio Streaming Daemon Apr 22 09:10:50 volumio volumio[1131]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 22 09:10:50 volumio volumio[1131]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 22 09:10:51 volumio volumio[1131]: error: MyVolumio Custom Token format not valid, refreshing it Apr 22 09:10:51 volumio volumio[1131]: STREAMING PROXY: Starting server on port 3245 Apr 22 09:10:51 volumio volumio[1131]: Node JS runtime: 14 Apr 22 09:10:52 volumio volumio[1131]: info: MyVolumio login type: Token Apr 22 09:10:53 volumio volumio[1131]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 22 09:10:53 volumio volumio[1131]: info: MyVolumio token set successfully Apr 22 09:10:53 volumio volumio[1131]: info: MYVOLUMIO: Adding device Apr 22 09:10:53 volumio volumio[1131]: info: MYVOLUMIO: Evaluating Server Apr 22 09:10:53 volumio volumio-time-update[672]: volumio-time-update-util: Fetching time from Volumio... Apr 22 09:10:54 volumio volumio-time-update[672]: volumio-time-update-util: Setting system time to: 2025-04-22 09:10:54 Apr 22 09:10:54 volumio sudo[2158]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-04-22 09:10:54 Apr 22 09:10:54 volumio sudo[2158]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 09:10:54 volumio dbus-daemon[678]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.13' (uid=0 pid=2160 comm="timedatectl set-time 2025-04-22 09:10:54 ") Apr 22 09:10:54 volumio systemd[1]: Starting Time & Date Service... Apr 22 09:10:54 volumio dbus-daemon[678]: [system] Successfully activated service 'org.freedesktop.timedate1' Apr 22 09:10:54 volumio systemd[1]: Started Time & Date Service. Apr 22 09:10:54 volumio systemd-timedated[2163]: Changed local time to Tue Apr 22 09:10:54 2025 Apr 22 09:10:54 volumio sudo[2158]: pam_unix(sudo:session): session closed for user root Apr 22 09:10:54 volumio volumio-time-update[672]: volumio-time-update-util: System time updated successfully. Apr 22 09:10:54 volumio systemd[1]: Started Volumio Time Update Utility. Apr 22 09:10:54 volumio systemd[1]: Reached target Multi-User System. Apr 22 09:10:54 volumio systemd[1]: Reached target Graphical Interface. Apr 22 09:10:54 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 22 09:10:54 volumio volumio[1131]: info: MyVolumio status changed Apr 22 09:10:54 volumio volumio[1131]: info: Streaming services startup Apr 22 09:10:54 volumio volumio[1131]: info: Starting Streaming Daemon Apr 22 09:10:54 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 22 09:10:54 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 22 09:10:54 volumio systemd[1]: Startup finished in 19.329s (kernel) + 3min 59.991s (userspace) = 4min 19.320s. Apr 22 09:10:54 volumio volumio[1131]: info: Removing browser output: myVolumio user plan is not superstar Apr 22 09:10:54 volumio volumio[1131]: info: Removing audio output: Apr 22 09:10:54 volumio volumio[1131]: info: Stoppping Tunnel 1 Apr 22 09:10:54 volumio sudo[2175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 22 09:10:54 volumio sudo[2175]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 09:10:54 volumio sudo[2175]: pam_unix(sudo:session): session closed for user root Apr 22 09:10:54 volumio volumio[1131]: error: Failed to ping endpoint us1.myvolumio.org : unknown error Apr 22 09:10:54 volumio volumio[1131]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 09:10:54 volumio sudo[2178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 22 09:10:54 volumio volumio[1131]: Error: Unable to resolve or reject the same promise twice Apr 22 09:10:54 volumio volumio[1131]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 22 09:10:54 volumio volumio[1131]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32367) Apr 22 09:10:54 volumio volumio[1131]: at Socket.emit (events.js:400:28) Apr 22 09:10:54 volumio volumio[1131]: at addChunk (internal/streams/readable.js:293:12) Apr 22 09:10:54 volumio volumio[1131]: at readableAddChunk (internal/streams/readable.js:267:9) Apr 22 09:10:54 volumio volumio[1131]: at Socket.Readable.push (internal/streams/readable.js:206:10) Apr 22 09:10:54 volumio volumio[1131]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Apr 22 09:10:54 volumio volumio[1131]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 09:10:54 volumio sudo[2178]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 09:10:54 volumio sudo[2178]: pam_unix(sudo:session): session closed for user root Apr 22 09:10:54 volumio sudo[2189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-22 09:09 Apr 22 09:10:54 volumio sudo[2189]: 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="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:27:48 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4bc1ecb08f34ad14b420d6678a2aef1b"