-- Logs begin at Sun 2024-07-21 18:23:51 NZST, end at Sun 2024-07-21 18:25:03 NZST. -- Jul 21 18:24:00 primo-kitchen volumio[833]: info: Loading plugin "outputs"... Jul 21 18:24:00 primo-kitchen volumio[833]: info: Loading plugin "albumart"... Jul 21 18:24:00 primo-kitchen volumio[833]: info: Plugin example_plugin is not enabled Jul 21 18:24:00 primo-kitchen volumio[833]: info: Loading plugin "inputs"... Jul 21 18:24:00 primo-kitchen volumio[833]: info: Loading plugin "updater_comm"... Jul 21 18:24:00 primo-kitchen volumio[833]: info: Plugin mpdemulation is not enabled Jul 21 18:24:00 primo-kitchen volumio[833]: info: Loading plugin "rest_api"... Jul 21 18:24:00 primo-kitchen volumio[833]: info: Loading plugin "websocket"... Jul 21 18:24:00 primo-kitchen volumio[833]: info: Starting Socket.io Server version 2.3.0 Jul 21 18:24:00 primo-kitchen volumio[833]: info: Loading i18n strings for locale en Jul 21 18:24:00 primo-kitchen volumio[833]: Updating browse sources language Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 21 18:24:00 primo-kitchen volumio[833]: Forking 3 albumart workers Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreCommandRouter::initPlayerControls Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 21 18:24:00 primo-kitchen volumio[833]: Express server listening on port 3000 Jul 21 18:24:00 primo-kitchen volumio[833]: [Metrics] WebUI: 4s 636.68ms Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreStateMachine::resetVolumioState Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreStateMachine::getcurrentVolume Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioRetrievevolume Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 18:24:00 primo-kitchen volumio[833]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jul 21 18:24:00 primo-kitchen volumio[833]: info: Completed loading Core Plugins Jul 21 18:24:00 primo-kitchen volumio[833]: info: Preparing to generate the ALSA configuration file Jul 21 18:24:00 primo-kitchen volumio-remote-updater[549]: [2024-07-21 18:24:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1721543039 101 Jul 21 18:24:00 primo-kitchen volumio[833]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jul 21 18:24:00 primo-kitchen volumio[833]: info: Reloading queue from file Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreStateMachine::setRepeat false single undefined Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreStateMachine::pushState Jul 21 18:24:00 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioPushState Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreStateMachine::setRandom true Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreStateMachine::pushState Jul 21 18:24:00 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioPushState Jul 21 18:24:00 primo-kitchen volumio[833]: info: Setting Device type: Tinkerboard Jul 21 18:24:00 primo-kitchen volumio[833]: info: VolumeController:: Volume=100 Mute =false Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreStateMachine::pushState Jul 21 18:24:00 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioPushState Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreStateMachine::updateTrackBlock Jul 21 18:24:00 primo-kitchen volumio[833]: info: CorePlayQueue::getTrackBlock Jul 21 18:24:00 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioRetrievevolume Jul 21 18:24:00 primo-kitchen volumio[833]: info: Asound.conf file written Jul 21 18:24:00 primo-kitchen sudo[976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 21 18:24:01 primo-kitchen sudo[976]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:01 primo-kitchen sudo[976]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:01 primo-kitchen volumio[833]: info: Output device has changed, restarting MPD Jul 21 18:24:01 primo-kitchen volumio[833]: info: Output device has changed, restarting Shairport Sync Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 18:24:01 primo-kitchen sudo[989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 21 18:24:01 primo-kitchen sudo[989]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:01 primo-kitchen sudo[990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 21 18:24:01 primo-kitchen volumio[833]: info: ___________ START PLUGINS ___________ Jul 21 18:24:01 primo-kitchen volumio[833]: info: ControllerMpd::onStart: Initializing MPD Jul 21 18:24:01 primo-kitchen volumio[833]: info: Creating MPD Configuration file Jul 21 18:24:01 primo-kitchen volumio[833]: Starting albumart workers Jul 21 18:24:01 primo-kitchen sudo[989]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:01 primo-kitchen sudo[990]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:01 primo-kitchen volumio[833]: Starting albumart workers Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 21 18:24:01 primo-kitchen volumio[833]: info: [1721543041209] CoreMusicLibrary::Adding element Media Servers Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 21 18:24:01 primo-kitchen sudo[1007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 18:24:01 primo-kitchen sudo[1007]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:01 primo-kitchen systemd[1]: Listening on mpd.socket. Jul 21 18:24:01 primo-kitchen systemd[1]: Starting Music Player Daemon... Jul 21 18:24:01 primo-kitchen volumio[833]: Starting albumart workers Jul 21 18:24:01 primo-kitchen sudo[1012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 21 18:24:01 primo-kitchen sudo[1007]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 18:24:01 primo-kitchen sudo[1012]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 21 18:24:01 primo-kitchen volumio[833]: info: [1721543041337] CoreMusicLibrary::Adding element Last_100 Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 21 18:24:01 primo-kitchen volumio[833]: info: [1721543041339] CoreMusicLibrary::Adding element Webradio Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 21 18:24:01 primo-kitchen volumio[833]: info: Initializing BBC Radios Jul 21 18:24:01 primo-kitchen sudo[1019]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 21 18:24:01 primo-kitchen sudo[1019]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:01 primo-kitchen sudo[1019]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 18:24:01 primo-kitchen volumio[833]: info: [now-playing] ConfigUpdater: config is up to date. Jul 21 18:24:01 primo-kitchen volumio[833]: info: Volumio Calling Home Jul 21 18:24:01 primo-kitchen systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 21 18:24:01 primo-kitchen systemd[1]: mpd.service: Succeeded. Jul 21 18:24:01 primo-kitchen systemd[1]: Stopped Music Player Daemon. Jul 21 18:24:01 primo-kitchen systemd[1]: Starting Music Player Daemon... Jul 21 18:24:01 primo-kitchen sudo[1050]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 21 18:24:01 primo-kitchen sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:01 primo-kitchen volumio[833]: info: [now-playing] App is listening on port 4004. Jul 21 18:24:01 primo-kitchen sudo[1050]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jul 21 18:24:01 primo-kitchen sudo[1050]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:01 primo-kitchen volumio[833]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jul 21 18:24:01 primo-kitchen volumio[833]: Unhandled rejection Error: No sockets available, cannot start. Jul 21 18:24:01 primo-kitchen volumio[833]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 21 18:24:01 primo-kitchen volumio[833]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 21 18:24:01 primo-kitchen volumio[833]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 21 18:24:01 primo-kitchen volumio[833]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 21 18:24:01 primo-kitchen volumio[833]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 21 18:24:01 primo-kitchen volumio[833]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 21 18:24:01 primo-kitchen volumio[833]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 21 18:24:01 primo-kitchen volumio[833]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 21 18:24:01 primo-kitchen volumio[833]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 21 18:24:01 primo-kitchen volumio[833]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 21 18:24:01 primo-kitchen volumio[833]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 21 18:24:01 primo-kitchen volumio[833]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 21 18:24:01 primo-kitchen volumio[833]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 21 18:24:01 primo-kitchen volumio[833]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 21 18:24:01 primo-kitchen volumio[833]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 21 18:24:01 primo-kitchen volumio[833]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 21 18:24:01 primo-kitchen volumio[833]: info: MPD Permissions set Jul 21 18:24:01 primo-kitchen volumio[833]: info: MPD Permissions set Jul 21 18:24:01 primo-kitchen volumio[833]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:01 primo-kitchen volumio[833]: info: VolumeController:: Volume=100 Mute =false Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreStateMachine::pushState Jul 21 18:24:01 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioPushState Jul 21 18:24:01 primo-kitchen volumio[833]: info: Starting Shairport Sync Jul 21 18:24:01 primo-kitchen volumio[833]: info: Starting Shairport Sync Jul 21 18:24:01 primo-kitchen volumio[833]: info: Starting Shairport Sync Jul 21 18:24:01 primo-kitchen sudo[1099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 21 18:24:01 primo-kitchen sudo[1101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 21 18:24:01 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioGetState Jul 21 18:24:01 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:01 primo-kitchen sudo[1099]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:01 primo-kitchen sudo[1106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 21 18:24:01 primo-kitchen sudo[1101]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:01 primo-kitchen sudo[1106]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:02 primo-kitchen systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 21 18:24:02 primo-kitchen systemd[1]: shairport-sync.service: Succeeded. Jul 21 18:24:02 primo-kitchen systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 21 18:24:02 primo-kitchen systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 21 18:24:02 primo-kitchen sudo[1099]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:02 primo-kitchen sudo[1101]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:02 primo-kitchen sudo[1106]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:02 primo-kitchen volumio[833]: info: Shairport-Sync Started Jul 21 18:24:02 primo-kitchen volumio[833]: Error adding Membership: Error: addMembership EINVAL Jul 21 18:24:02 primo-kitchen volumio[833]: info: Shairport-Sync Started Jul 21 18:24:02 primo-kitchen volumio[833]: info: Shairport-Sync Started Jul 21 18:24:03 primo-kitchen mpd[1082]: Jul 21 18:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 21 18:24:03 primo-kitchen systemd[1]: Started Music Player Daemon. Jul 21 18:24:03 primo-kitchen sudo[990]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:03 primo-kitchen sudo[1012]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:03 primo-kitchen volumio[833]: info: Completed starting Core Plugins Jul 21 18:24:03 primo-kitchen volumio[833]: info: ------------------------------------------- Jul 21 18:24:03 primo-kitchen volumio[833]: info: ----- MyVolumio plugins startup ---- Jul 21 18:24:03 primo-kitchen volumio[833]: info: ------------------------------------------- Jul 21 18:24:03 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 21 18:24:03 primo-kitchen volumio[833]: error: MPD error: The expression evaluated to a falsy value: Jul 21 18:24:03 primo-kitchen volumio[833]: assert.ok(self.idling) Jul 21 18:24:03 primo-kitchen volumio[833]: error: The expression evaluated to a falsy value: Jul 21 18:24:03 primo-kitchen volumio[833]: assert.ok(self.idling) Jul 21 18:24:03 primo-kitchen volumio[833]: info: MPD running with PID1082 Jul 21 18:24:03 primo-kitchen volumio[833]: ,establishing connection Jul 21 18:24:03 primo-kitchen volumio[833]: error: updateQueue error: null Jul 21 18:24:03 primo-kitchen volumio[833]: error: updateQueue error: null Jul 21 18:24:03 primo-kitchen dhcpcd[669]: eth0: leased 192.168.1.50 for 691200 seconds Jul 21 18:24:03 primo-kitchen avahi-daemon[533]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.50. Jul 21 18:24:03 primo-kitchen dhcpcd[669]: eth0: adding route to 192.168.1.0/24 Jul 21 18:24:03 primo-kitchen avahi-daemon[533]: New relevant interface eth0.IPv4 for mDNS. Jul 21 18:24:03 primo-kitchen dhcpcd[669]: eth0: adding default route via 192.168.1.1 Jul 21 18:24:03 primo-kitchen avahi-daemon[533]: Registering new address record for 192.168.1.50 on eth0.IPv4. Jul 21 18:24:03 primo-kitchen volumio[833]: verbose: New Socket.io Connection to 192.168.1.50 from 192.168.1.68 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jul 21 18:24:03 primo-kitchen volumio[833]: verbose: New Socket.io Connection to 192.168.1.50 from 192.168.1.68 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jul 21 18:24:03 primo-kitchen ntpd[788]: ntpd exiting on signal 15 (Terminated) Jul 21 18:24:03 primo-kitchen systemd[1]: Stopping Network Time Service... Jul 21 18:24:03 primo-kitchen systemd[1]: ntp.service: Succeeded. Jul 21 18:24:03 primo-kitchen systemd[1]: Stopped Network Time Service. Jul 21 18:24:03 primo-kitchen systemd[1]: Starting Network Time Service... Jul 21 18:24:03 primo-kitchen ntpd[1175]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 21 18:24:03 primo-kitchen ntpd[1175]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jul 21 18:24:03 primo-kitchen systemd[1]: Started Network Time Service. Jul 21 18:24:03 primo-kitchen ntpd[1181]: proto: precision = 0.583 usec (-21) Jul 21 18:24:03 primo-kitchen ntpd[1181]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 21 18:24:03 primo-kitchen ntpd[1181]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 21 18:24:03 primo-kitchen ntpd[1181]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 572 days ago Jul 21 18:24:03 primo-kitchen ntpd[1181]: Listen and drop on 0 v6wildcard [::]:123 Jul 21 18:24:03 primo-kitchen ntpd[1181]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 21 18:24:03 primo-kitchen ntpd[1181]: Listen normally on 2 lo 127.0.0.1:123 Jul 21 18:24:03 primo-kitchen ntpd[1181]: Listen normally on 3 eth0 192.168.1.50:123 Jul 21 18:24:03 primo-kitchen ntpd[1181]: Listening on routing socket on fd #20 for interface updates Jul 21 18:24:03 primo-kitchen ntpd[1181]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 21 18:24:03 primo-kitchen ntpd[1181]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 21 18:24:03 primo-kitchen volumio[833]: info: Discovery: adding 6eb371b3-25a6-467d-ab2b-f9e7ced12256 Jul 21 18:24:03 primo-kitchen volumio[833]: info: Discovery: Found device Headfi Stack Jul 21 18:24:03 primo-kitchen volumio[833]: info: Discovery: Connecting to remote: 192.168.1.55 Jul 21 18:24:03 primo-kitchen sh[485]: eth0: leased 192.168.1.50 for 691200 seconds Jul 21 18:24:03 primo-kitchen sh[485]: eth0: adding route to 192.168.1.0/24 Jul 21 18:24:03 primo-kitchen sh[485]: eth0: adding default route via 192.168.1.1 Jul 21 18:24:03 primo-kitchen dhcpcd[525]: eth0: leased 192.168.1.50 for 691200 seconds Jul 21 18:24:03 primo-kitchen dhcpcd[525]: eth0: adding route to 192.168.1.0/24 Jul 21 18:24:03 primo-kitchen dhcpcd[525]: eth0: adding default route via 192.168.1.1 Jul 21 18:24:03 primo-kitchen volumio[833]: info: Discovery: Connected to remote: 192.168.1.55 Jul 21 18:24:03 primo-kitchen volumio[833]: info: Discovery: adding e17865ee-3475-454d-9c0b-6ece07f3c16b Jul 21 18:24:03 primo-kitchen volumio[833]: info: Discovery: Found device Rivo Lounge Jul 21 18:24:03 primo-kitchen volumio[833]: info: Discovery: Connecting to remote: 192.168.1.100 Jul 21 18:24:03 primo-kitchen volumio[833]: info: Discovery: Connected to remote: 192.168.1.100 Jul 21 18:24:03 primo-kitchen ntpd[1181]: ntpd exiting on signal 15 (Terminated) Jul 21 18:24:03 primo-kitchen systemd[1]: Stopping Network Time Service... Jul 21 18:24:03 primo-kitchen systemd[1]: ntp.service: Succeeded. Jul 21 18:24:03 primo-kitchen systemd[1]: Stopped Network Time Service. Jul 21 18:24:03 primo-kitchen systemd[1]: Starting Network Time Service... Jul 21 18:24:03 primo-kitchen ntpd[1219]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 21 18:24:03 primo-kitchen ntpd[1219]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jul 21 18:24:03 primo-kitchen systemd[1]: Started Network Time Service. Jul 21 18:24:03 primo-kitchen ntpd[1225]: proto: precision = 0.583 usec (-21) Jul 21 18:24:03 primo-kitchen ntpd[1225]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 21 18:24:03 primo-kitchen ntpd[1225]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 21 18:24:03 primo-kitchen ntpd[1225]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 572 days ago Jul 21 18:24:03 primo-kitchen ntpd[1225]: Listen and drop on 0 v6wildcard [::]:123 Jul 21 18:24:03 primo-kitchen sh[485]: forked to background, child pid 1226 Jul 21 18:24:03 primo-kitchen dhcpcd[525]: forked to background, child pid 1226 Jul 21 18:24:03 primo-kitchen ntpd[1225]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 21 18:24:03 primo-kitchen ntpd[1225]: Listen normally on 2 lo 127.0.0.1:123 Jul 21 18:24:03 primo-kitchen ntpd[1225]: Listen normally on 3 eth0 192.168.1.50:123 Jul 21 18:24:03 primo-kitchen ntpd[1225]: Listening on routing socket on fd #20 for interface updates Jul 21 18:24:03 primo-kitchen ntpd[1225]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 21 18:24:03 primo-kitchen ntpd[1225]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 21 18:24:04 primo-kitchen ifplugd(eth0)[810]: client: ifup: interface eth0 already configured Jul 21 18:24:04 primo-kitchen sh[485]: eth0=eth0 Jul 21 18:24:04 primo-kitchen ifplugd(eth0)[810]: Program executed successfully. Jul 21 18:24:04 primo-kitchen ifplugd[756]: Network Interface Plugging Daemon...start eth0...done. Jul 21 18:24:04 primo-kitchen systemd[1]: Started LSB: Brings up/down network automatically. Jul 21 18:24:04 primo-kitchen volumio[833]: verbose: New Socket.io Connection to 192.168.1.50:3000 from 192.168.1.100 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 21 18:24:04 primo-kitchen volumio[833]: info: Discovery: adding 467b9632-ea8f-418b-96c4-a103e59373b5 Jul 21 18:24:04 primo-kitchen volumio[833]: info: Discovery: Found device Primo Kitchen Jul 21 18:24:04 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioGetState Jul 21 18:24:04 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:04 primo-kitchen volumio[833]: verbose: New Socket.io Connection to 192.168.1.50 from 192.168.1.68 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jul 21 18:24:05 primo-kitchen ntpd[1225]: Soliciting pool server 202.124.96.215 Jul 21 18:24:05 primo-kitchen nmbd[760]: [2024/07/21 18:24:05.078045, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 21 18:24:05 primo-kitchen nmbd[760]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jul 21 18:24:05 primo-kitchen systemd[1]: Started Samba NMB Daemon. Jul 21 18:24:05 primo-kitchen systemd[1]: Starting Samba Winbind Daemon... Jul 21 18:24:05 primo-kitchen kernel: nf_conntrack: automatic helper assignment is deprecated and it will be removed soon. Use the iptables CT target to attach helpers instead. Jul 21 18:24:05 primo-kitchen nmbd[760]: [2024/07/21 18:24:05.086308, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jul 21 18:24:05 primo-kitchen nmbd[760]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.50 for name WORKGROUP<1d>. Jul 21 18:24:05 primo-kitchen nmbd[760]: This response was from IP 192.168.1.54, reporting an IP address of 192.168.1.54. Jul 21 18:24:05 primo-kitchen winbindd[1253]: [2024/07/21 18:24:05.249669, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jul 21 18:24:05 primo-kitchen winbindd[1253]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jul 21 18:24:05 primo-kitchen winbindd[1253]: [2024/07/21 18:24:05.257853, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 21 18:24:05 primo-kitchen systemd[1]: Started Samba Winbind Daemon. Jul 21 18:24:05 primo-kitchen winbindd[1253]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jul 21 18:24:05 primo-kitchen systemd[1]: Starting Samba SMB Daemon... Jul 21 18:24:05 primo-kitchen smbd[1258]: [2024/07/21 18:24:05.556965, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 21 18:24:05 primo-kitchen smbd[1258]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 21 18:24:05 primo-kitchen systemd[1]: Started Samba SMB Daemon. Jul 21 18:24:05 primo-kitchen systemd[1]: Reached target Multi-User System. Jul 21 18:24:05 primo-kitchen systemd[1]: Reached target Graphical Interface. Jul 21 18:24:05 primo-kitchen systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 21 18:24:05 primo-kitchen systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 21 18:24:05 primo-kitchen systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 21 18:24:05 primo-kitchen systemd[1]: Startup finished in 14.392s (kernel) + 14.389s (userspace) = 28.782s. Jul 21 18:24:05 primo-kitchen ntpd[1225]: Soliciting pool server 203.114.153.177 Jul 21 18:24:06 primo-kitchen volumio[833]: verbose: New Socket.io Connection to 192.168.1.50:3000 from 192.168.1.100 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jul 21 18:24:06 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jul 21 18:24:06 primo-kitchen ntpd[1225]: Soliciting pool server 202.21.136.135 Jul 21 18:24:06 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioGetState Jul 21 18:24:06 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:06 primo-kitchen ntpd[1225]: Soliciting pool server 202.56.44.243 Jul 21 18:24:06 primo-kitchen ntpd[1225]: Soliciting pool server 162.159.200.1 Jul 21 18:24:07 primo-kitchen ntpd[1225]: Soliciting pool server 202.68.92.244 Jul 21 18:24:07 primo-kitchen volumio[833]: verbose: New Socket.io Connection to 192.168.1.50 from 192.168.1.68 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jul 21 18:24:07 primo-kitchen volumio[833]: verbose: New Socket.io Connection to 192.168.1.50 from 192.168.1.68 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jul 21 18:24:07 primo-kitchen volumio[833]: verbose: New Socket.io Connection to 192.168.1.50 from 192.168.1.68 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jul 21 18:24:07 primo-kitchen volumio[833]: verbose: New Socket.io Connection to 192.168.1.50 from 192.168.1.68 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jul 21 18:24:07 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 21 18:24:07 primo-kitchen ntpd[1225]: Soliciting pool server 101.100.138.250 Jul 21 18:24:07 primo-kitchen ntpd[1225]: Soliciting pool server 103.242.68.69 Jul 21 18:24:07 primo-kitchen ntpd[1225]: Soliciting pool server 43.252.70.34 Jul 21 18:24:07 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioGetVisibleSources Jul 21 18:24:07 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 21 18:24:07 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioGetState Jul 21 18:24:07 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:07 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioGetState Jul 21 18:24:07 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:07 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 21 18:24:07 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 21 18:24:07 primo-kitchen volumio[833]: info: Received Get System Info Jul 21 18:24:07 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 21 18:24:07 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 21 18:24:07 primo-kitchen volumio[833]: info: Discovery: Getting this device information Jul 21 18:24:07 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioGetState Jul 21 18:24:07 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:07 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 21 18:24:07 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioGetState Jul 21 18:24:07 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:07 primo-kitchen volumio[833]: info: Listing playlists Jul 21 18:24:08 primo-kitchen volumio[833]: verbose: New Socket.io Connection to 192.168.1.50:3000 from 192.168.1.55 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Jul 21 18:24:08 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jul 21 18:24:08 primo-kitchen volumio[833]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 21 18:24:08 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioGetState Jul 21 18:24:08 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:08 primo-kitchen ntpd[1225]: Soliciting pool server 123.255.62.42 Jul 21 18:24:08 primo-kitchen sudo[1270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 21 18:24:08 primo-kitchen sudo[1271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 21 18:24:08 primo-kitchen sudo[1270]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:08 primo-kitchen sudo[1271]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:08 primo-kitchen sudo[1270]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:08 primo-kitchen sudo[1275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 21 18:24:08 primo-kitchen sudo[1271]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:08 primo-kitchen sudo[1275]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:08 primo-kitchen systemd[1]: Started UPnP Renderer front-end to MPD. Jul 21 18:24:08 primo-kitchen sudo[1275]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:08 primo-kitchen volumio[833]: info: Upmpdcli Daemon Started Jul 21 18:24:08 primo-kitchen volumio[1295]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 21 18:24:08 primo-kitchen ntpd[1225]: Soliciting pool server 103.242.70.5 Jul 21 18:24:08 primo-kitchen ntpd[1225]: Soliciting pool server 103.106.65.219 Jul 21 18:24:08 primo-kitchen ntpd[1225]: Soliciting pool server 103.242.68.68 Jul 21 18:24:09 primo-kitchen ntpd[1225]: Soliciting pool server 2606:4700:f1::1 Jul 21 18:24:11 primo-kitchen volumio[833]: verbose: New Socket.io Connection to 192.168.1.50:3000 from 192.168.1.100 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Jul 21 18:24:11 primo-kitchen volumio[833]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=tinkerboard&variante=volumio&version=3.703&uuid=5a737ad5e22167c99b78b67ada731338" http://updates.volumio.org/downloader-v1/track-device Jul 21 18:24:11 primo-kitchen volumio[833]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 21 18:24:11 primo-kitchen volumio[833]: Dload Upload Total Spent Left Speed Jul 21 18:24:11 primo-kitchen volumio[833]: [132B blob data] Jul 21 18:24:11 primo-kitchen volumio[833]: retrying in 5 seconds, trial 0 Jul 21 18:24:11 primo-kitchen volumio[833]: info: Volumio Calling Home Jul 21 18:24:11 primo-kitchen volumio[1295]: .................................................++++ Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 21 18:24:11 primo-kitchen volumio[833]: info: Adding plugin bluetooth to MyMusic Plugins Jul 21 18:24:11 primo-kitchen volumio[833]: info: Adding plugin multiroom to MyMusic Plugins Jul 21 18:24:11 primo-kitchen volumio[833]: info: Adding plugin metavolumio to MyMusic Plugins Jul 21 18:24:11 primo-kitchen volumio[833]: info: Adding plugin cd_controller to MyMusic Plugins Jul 21 18:24:11 primo-kitchen volumio[833]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 21 18:24:11 primo-kitchen volumio[833]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 21 18:24:11 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 21 18:24:12 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 21 18:24:12 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 21 18:24:12 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 18:24:12 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 18:24:12 primo-kitchen volumio[833]: info: Starting MyVolumio Remote Streaming Endpoints Jul 21 18:24:12 primo-kitchen volumio[833]: info: MyVolumio login type: Token Jul 21 18:24:12 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 21 18:24:12 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 21 18:24:14 primo-kitchen ntpd[1225]: receive: Unexpected origin timestamp 0xea47280d.f253a432 does not match aorg 0000000000.00000000 from server@43.252.70.34 xmt 0xea47280e.655f2452 Jul 21 18:24:14 primo-kitchen ntpd[1225]: receive: Unexpected origin timestamp 0xea47280d.f2554373 does not match aorg 0000000000.00000000 from server@202.68.92.244 xmt 0xea47280e.66830b56 Jul 21 18:24:14 primo-kitchen ntpd[1225]: receive: Unexpected origin timestamp 0xea47280d.f24e3aae does not match aorg 0000000000.00000000 from server@103.242.70.5 xmt 0xea47280e.677c433a Jul 21 18:24:14 primo-kitchen ntpd[1225]: receive: Unexpected origin timestamp 0xea47280d.f25846a0 does not match aorg 0000000000.00000000 from server@203.114.153.177 xmt 0xea47280e.67c66c8c Jul 21 18:24:14 primo-kitchen ntpd[1225]: receive: Unexpected origin timestamp 0xea47280d.f259d973 does not match aorg 0000000000.00000000 from server@202.124.96.215 xmt 0xea47280e.67ea29d8 Jul 21 18:24:14 primo-kitchen ntpd[1225]: receive: Unexpected origin timestamp 0xea47280d.f2520a65 does not match aorg 0000000000.00000000 from server@123.255.62.42 xmt 0xea47280e.689ea11e Jul 21 18:24:14 primo-kitchen ntpd[1225]: receive: Unexpected origin timestamp 0xea47280d.f2471869 does not match aorg 0000000000.00000000 from server@103.106.65.219 xmt 0xea47280e.6af34cd2 Jul 21 18:24:14 primo-kitchen volumio[833]: info: Starting Streaming Service Transparent Proxy Jul 21 18:24:14 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 21 18:24:14 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 21 18:24:14 primo-kitchen volumio[833]: info: Streaming services startup Jul 21 18:24:14 primo-kitchen volumio[833]: info: Starting Streaming Daemon Jul 21 18:24:14 primo-kitchen sudo[1325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 21 18:24:14 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 21 18:24:14 primo-kitchen sudo[1325]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:14 primo-kitchen sudo[1325]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:14 primo-kitchen volumio[833]: info: Volumio called home Jul 21 18:24:14 primo-kitchen volumio[833]: error: Cannot start Volumio Streaming Daemon Jul 21 18:24:14 primo-kitchen volumio[833]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 21 18:24:14 primo-kitchen volumio[833]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 21 18:24:15 primo-kitchen volumio[833]: STREAMING PROXY: Starting server on port 3245 Jul 21 18:24:15 primo-kitchen volumio[833]: Node JS runtime: 14 Jul 21 18:24:15 primo-kitchen volumio[833]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 21 18:24:16 primo-kitchen volumio[833]: info: MyVolumio token set successfully Jul 21 18:24:16 primo-kitchen volumio[833]: info: MYVOLUMIO: Adding device Jul 21 18:24:16 primo-kitchen volumio[833]: info: MYVOLUMIO: Evaluating Server Jul 21 18:24:16 primo-kitchen volumio[833]: info: Upgrading User Account from device code Jul 21 18:24:16 primo-kitchen volumio[833]: info: MyVolumio Plan changed: superstar Jul 21 18:24:16 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Jul 21 18:24:16 primo-kitchen volumio[833]: info: Adding audio output: Jul 21 18:24:16 primo-kitchen volumio[833]: info: MYVOLUMIO: Adding device Jul 21 18:24:16 primo-kitchen volumio[833]: info: MYVOLUMIO: Evaluating Server Jul 21 18:24:16 primo-kitchen volumio[833]: info: Remote config written successfully Jul 21 18:24:16 primo-kitchen volumio[833]: info: Starting Tunnel 1 Jul 21 18:24:16 primo-kitchen volumio[833]: info: Starting Tunnel Connection Checker Jul 21 18:24:16 primo-kitchen volumio[833]: info: Completed starting MyVolumio Plugin Jul 21 18:24:17 primo-kitchen volumio[833]: info: MYVolumio Device enabled Jul 21 18:24:17 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jul 21 18:24:17 primo-kitchen volumio[833]: info: MyVolumio status changed Jul 21 18:24:17 primo-kitchen volumio[833]: info: Streaming services startup Jul 21 18:24:17 primo-kitchen volumio[833]: info: Starting Streaming Daemon Jul 21 18:24:17 primo-kitchen volumio[833]: info: Setting Geolocation for MyVolumio to au1 Jul 21 18:24:17 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 18:24:17 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 18:24:17 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 18:24:17 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jul 21 18:24:17 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jul 21 18:24:17 primo-kitchen sudo[1372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 21 18:24:17 primo-kitchen sudo[1372]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:17 primo-kitchen sudo[1372]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:17 primo-kitchen volumio[833]: info: [1721543057310] Starting BluetoothController Jul 21 18:24:17 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jul 21 18:24:18 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jul 21 18:24:18 primo-kitchen sudo[1382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jul 21 18:24:18 primo-kitchen sudo[1382]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:18 primo-kitchen sudo[1382]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:18 primo-kitchen volumio[833]: info: MRS: MultiRoom plugin initialized Jul 21 18:24:18 primo-kitchen volumio[833]: info: MRS: STOPPING SNAPCLIENT Jul 21 18:24:18 primo-kitchen volumio[833]: info: MRS: Snap server stop Jul 21 18:24:18 primo-kitchen volumio[833]: info: MRS: STOPPING volumioStreaming Jul 21 18:24:18 primo-kitchen sudo[1405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jul 21 18:24:18 primo-kitchen sudo[1405]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:18 primo-kitchen sudo[1408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jul 21 18:24:18 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jul 21 18:24:18 primo-kitchen sudo[1408]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:18 primo-kitchen sudo[1405]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:18 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jul 21 18:24:18 primo-kitchen sudo[1413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jul 21 18:24:18 primo-kitchen sudo[1422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jul 21 18:24:18 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jul 21 18:24:18 primo-kitchen sudo[1408]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:18 primo-kitchen sudo[1413]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:18 primo-kitchen sudo[1422]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:18 primo-kitchen sudo[1422]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:18 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jul 21 18:24:18 primo-kitchen sudo[1413]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:18 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jul 21 18:24:18 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jul 21 18:24:18 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jul 21 18:24:18 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jul 21 18:24:18 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jul 21 18:24:18 primo-kitchen volumio[833]: info: Preparing to generate the ALSA configuration file Jul 21 18:24:18 primo-kitchen volumio[833]: info: Updating MyVolumio device info Jul 21 18:24:18 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 18:24:18 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 18:24:18 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 18:24:18 primo-kitchen volumio[833]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 21 18:24:18 primo-kitchen volumio[833]: info: Reading ALSA contributions from plugins. Jul 21 18:24:18 primo-kitchen volumio[833]: error: Could not upgrade user account. Error: Device already associated to another id Jul 21 18:24:18 primo-kitchen volumio[833]: info: Setting Geolocation for MyVolumio to au1 Jul 21 18:24:18 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 18:24:18 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 18:24:18 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 18:24:18 primo-kitchen volumio[833]: info: MRS: Removed streaming files Jul 21 18:24:18 primo-kitchen volumio[833]: info: MRS: volumioStreaming STOPPED Jul 21 18:24:18 primo-kitchen volumio[833]: info: MRS: SNAPSERVER STOPPED Jul 21 18:24:18 primo-kitchen volumio[833]: info: MRS: SNAPCLIENT STOPPED Jul 21 18:24:18 primo-kitchen volumio[833]: error: Cannot start Volumio Streaming Daemon Jul 21 18:24:18 primo-kitchen volumio[833]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 21 18:24:18 primo-kitchen volumio[833]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 21 18:24:18 primo-kitchen volumio[833]: info: Asound.conf file written Jul 21 18:24:18 primo-kitchen sudo[1445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 21 18:24:18 primo-kitchen sudo[1445]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:19 primo-kitchen sudo[1445]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:19 primo-kitchen volumio[833]: info: Output device has changed, restarting MPD Jul 21 18:24:19 primo-kitchen volumio[833]: info: Output device has changed, restarting Shairport Sync Jul 21 18:24:19 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:19 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 18:24:19 primo-kitchen sudo[1457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 21 18:24:19 primo-kitchen sudo[1457]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:19 primo-kitchen volumio[833]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 21 18:24:19 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:19 primo-kitchen sudo[1460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 21 18:24:19 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jul 21 18:24:19 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 21 18:24:19 primo-kitchen sudo[1457]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:19 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jul 21 18:24:19 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jul 21 18:24:19 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output for this device Jul 21 18:24:19 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output Jul 21 18:24:19 primo-kitchen sudo[1460]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:19 primo-kitchen volumio[833]: info: Adding audio output: Jul 21 18:24:19 primo-kitchen volumio[833]: info: Adding audio output: Jul 21 18:24:19 primo-kitchen volumio[833]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 21 18:24:19 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:19 primo-kitchen sudo[1477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Primo Kitchen Jul 21 18:24:19 primo-kitchen systemd[1]: Stopping Music Player Daemon... Jul 21 18:24:19 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jul 21 18:24:19 primo-kitchen volumio[833]: info: Adding METAVOLUMIO REST API Endpoints Jul 21 18:24:19 primo-kitchen volumio[833]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jul 21 18:24:19 primo-kitchen volumio[833]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jul 21 18:24:19 primo-kitchen volumio[833]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jul 21 18:24:19 primo-kitchen volumio[833]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jul 21 18:24:19 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jul 21 18:24:19 primo-kitchen volumio[833]: info: Adding Manifest REST API Endpoints Jul 21 18:24:19 primo-kitchen volumio[833]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Jul 21 18:24:19 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jul 21 18:24:19 primo-kitchen volumio[833]: info: Preparing CD Folders Jul 21 18:24:19 primo-kitchen volumio[833]: info: Adding CD REST API Endpoints Jul 21 18:24:19 primo-kitchen volumio[833]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jul 21 18:24:19 primo-kitchen volumio[833]: info: Starting UDEV Watcher for CD Jul 21 18:24:19 primo-kitchen volumio[833]: info: Detecting CD presence with UDEV Jul 21 18:24:19 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jul 21 18:24:19 primo-kitchen sudo[1477]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:19 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jul 21 18:24:19 primo-kitchen volumio[833]: info: Adding inputs REST Endpoints Jul 21 18:24:19 primo-kitchen volumio[833]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jul 21 18:24:19 primo-kitchen volumio[833]: info: Scanning Audio Inputs Jul 21 18:24:19 primo-kitchen volumio[833]: info: Checking against Known Cards name Jul 21 18:24:19 primo-kitchen volumio[833]: info: Checking against Known Cards name Jul 21 18:24:19 primo-kitchen volumio[833]: info: Checking against Known Cards name Jul 21 18:24:19 primo-kitchen volumio[833]: info: Adding Server instance for streaming Jul 21 18:24:19 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jul 21 18:24:19 primo-kitchen volumio[833]: error: Hi Res Audio Failed Login: Missing Login Data Jul 21 18:24:19 primo-kitchen volumio[833]: info: Adding HIGHRESAUDIO REST API Endpoints Jul 21 18:24:19 primo-kitchen volumio[833]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jul 21 18:24:19 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jul 21 18:24:19 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jul 21 18:24:19 primo-kitchen volumio[833]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jul 21 18:24:19 primo-kitchen volumio[833]: info: Stopping AccessToken refresher cron Jul 21 18:24:19 primo-kitchen sudo[1492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 21 18:24:19 primo-kitchen sudo[1492]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:19 primo-kitchen systemd[1]: mpd.service: Succeeded. Jul 21 18:24:19 primo-kitchen systemd[1]: Stopped Music Player Daemon. Jul 21 18:24:19 primo-kitchen systemd[1]: Starting Music Player Daemon... Jul 21 18:24:19 primo-kitchen volumio[833]: info: AccessToken refresher cron started Jul 21 18:24:19 primo-kitchen volumio[833]: info: Stopping AccessToken refresher cron for QOBUZ Jul 21 18:24:19 primo-kitchen volumio[833]: info: AccessToken refresher cron started for QOBUZ Jul 21 18:24:19 primo-kitchen sudo[1492]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:19 primo-kitchen volumio[833]: info: Adding TIDAL REST API Endpoints Jul 21 18:24:19 primo-kitchen volumio[833]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jul 21 18:24:19 primo-kitchen volumio[833]: info: Adding QOBUZ REST API Endpoints Jul 21 18:24:19 primo-kitchen volumio[833]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jul 21 18:24:19 primo-kitchen volumio[833]: info: MRS: Adding multiroomSync output Jul 21 18:24:19 primo-kitchen volumio[833]: info: MRS: This device was ungracefully shutdown, cleaning up Jul 21 18:24:19 primo-kitchen volumio[833]: info: MRS: Removing another device as multiroomSync client Jul 21 18:24:19 primo-kitchen volumio[833]: info: Adding audio output: Jul 21 18:24:19 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output Jul 21 18:24:19 primo-kitchen volumio[833]: info: MPD Permissions set Jul 21 18:24:19 primo-kitchen sudo[1498]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 21 18:24:19 primo-kitchen sudo[1498]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:19 primo-kitchen sudo[1498]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:19 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:19 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:19 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:19 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:19 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:19 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:19 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:19 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:19 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 18:24:19 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 21 18:24:19 primo-kitchen sudo[1477]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:19 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:19 primo-kitchen volumio[833]: info: MRS: Updating multiroomSync output Jul 21 18:24:19 primo-kitchen volumio[833]: info: MRS: Adding multiroomSync output Jul 21 18:24:19 primo-kitchen volumio[833]: info: Adding audio output: Jul 21 18:24:19 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output Jul 21 18:24:19 primo-kitchen volumio[833]: info: Bluetooth name changed to Primo Kitchen Jul 21 18:24:19 primo-kitchen volumio[833]: info: Starting Shairport Sync Jul 21 18:24:19 primo-kitchen volumio[833]: info: MRS: Found cast device: SHIELD-Android-TV-f0b4ee04271677f4c681e169c8e403b6 Jul 21 18:24:19 primo-kitchen volumio[833]: info: Adding audio output: Jul 21 18:24:19 primo-kitchen sudo[1524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 21 18:24:19 primo-kitchen volumio[833]: info: MRS: Found cast device: Chromecast-1de6fdd9e991446058248e95e9b5d5ef Jul 21 18:24:19 primo-kitchen volumio[833]: info: Adding audio output: Jul 21 18:24:19 primo-kitchen sudo[1524]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:19 primo-kitchen volumio[833]: info: MRS: Updating multiroomSync output Jul 21 18:24:19 primo-kitchen systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 21 18:24:19 primo-kitchen systemd[1]: shairport-sync.service: Succeeded. Jul 21 18:24:19 primo-kitchen systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 21 18:24:19 primo-kitchen systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 21 18:24:19 primo-kitchen sudo[1524]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:19 primo-kitchen volumio[833]: info: Shairport-Sync Started Jul 21 18:24:19 primo-kitchen volumio[833]: info: Successfully Added MyVolumio device Jul 21 18:24:19 primo-kitchen volumio[833]: info: Successfully Updated MyVolumio device Jul 21 18:24:19 primo-kitchen volumio[833]: info: Successfully Added MyVolumio device Jul 21 18:24:19 primo-kitchen volumio[833]: info: Updating MyVolumio device info Jul 21 18:24:19 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 18:24:19 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 18:24:19 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 18:24:20 primo-kitchen mpd[1519]: Jul 21 18:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 21 18:24:20 primo-kitchen systemd[1]: Started Music Player Daemon. Jul 21 18:24:20 primo-kitchen sudo[1460]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:20 primo-kitchen volumio[833]: error: updateQueue error: null Jul 21 18:24:21 primo-kitchen volumio[833]: info: Successfully Updated MyVolumio device Jul 21 18:24:21 primo-kitchen sudo[1546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jul 21 18:24:21 primo-kitchen sudo[1546]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:21 primo-kitchen systemd[1]: Started MyVolumio SSH Tunnel. Jul 21 18:24:21 primo-kitchen sudo[1546]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:21 primo-kitchen volumio[833]: info: Remote SSH Started Jul 21 18:24:21 primo-kitchen autossh[1553]: port set to 0, monitoring disabled Jul 21 18:24:21 primo-kitchen autossh[1553]: starting ssh (count 1) Jul 21 18:24:21 primo-kitchen autossh[1553]: ssh child pid is 1559 Jul 21 18:24:22 primo-kitchen volumio[833]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Jul 21 18:24:22 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioGetState Jul 21 18:24:22 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:22 primo-kitchen volumiossh-tunnel[1552]: Warning: Permanently added '[au1.myvolumio.org]:2222,[170.64.163.26]:2222' (RSA) to the list of known hosts. Jul 21 18:24:22 primo-kitchen sudo[1561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jul 21 18:24:22 primo-kitchen sudo[1561]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:22 primo-kitchen systemd[1]: Started Volumio Bluetooth Module. Jul 21 18:24:22 primo-kitchen sudo[1561]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:22 primo-kitchen volumio[833]: info: Volumio BT Module successfully started Jul 21 18:24:22 primo-kitchen sudo[1570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jul 21 18:24:22 primo-kitchen sudo[1570]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:22 primo-kitchen volumio[1295]: .......................................................................................................................................................++++ Jul 21 18:24:22 primo-kitchen volumio[1295]: e is 65537 (0x010001) Jul 21 18:24:22 primo-kitchen volumiobt[1567]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jul 21 18:24:22 primo-kitchen sudo[1570]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:22 primo-kitchen volumio[1295]: writing RSA key Jul 21 18:24:22 primo-kitchen volumio[833]: info: TidalConnect service stoped! Jul 21 18:24:22 primo-kitchen volumio[833]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 21 18:24:22 primo-kitchen volumio[833]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 21 18:24:22 primo-kitchen sudo[1602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 21 18:24:22 primo-kitchen sudo[1602]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:22 primo-kitchen systemd[1]: Started Volumio Tidal Connect Service. Jul 21 18:24:22 primo-kitchen sudo[1602]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:22 primo-kitchen volumio[833]: info: Executing endpoint tc_getconfig Jul 21 18:24:22 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jul 21 18:24:22 primo-kitchen vtcs[1611]: STARTING TidalConnect services, version: 1.4.0.34 Jul 21 18:24:22 primo-kitchen vtcs[1611]: STARTED TidalConnect services. Jul 21 18:24:22 primo-kitchen volumio[833]: info: Executing endpoint tc_connect Jul 21 18:24:22 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jul 21 18:24:22 primo-kitchen volumio[833]: info: Connecting to TidalConnect Jul 21 18:24:22 primo-kitchen volumio[833]: info: CoreCommandRouter::servicePushState Jul 21 18:24:22 primo-kitchen volumio[833]: info: CoreStateMachine::pushState Jul 21 18:24:22 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:22 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioPushState Jul 21 18:24:22 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output update for this device Jul 21 18:24:22 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output Jul 21 18:24:22 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:22 primo-kitchen volumio[833]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jul 21 18:24:22 primo-kitchen volumio[833]: verbose: CURRENT POSITION 0 Jul 21 18:24:22 primo-kitchen volumio[833]: info: CoreStateMachine::syncState stateService stop Jul 21 18:24:22 primo-kitchen volumio[833]: info: CoreStateMachine::syncState currentStatus stop Jul 21 18:24:22 primo-kitchen volumio[833]: info: CoreStateMachine::pushState Jul 21 18:24:22 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:22 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 18:24:22 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioPushState Jul 21 18:24:22 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output update for this device Jul 21 18:24:22 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output Jul 21 18:24:22 primo-kitchen volumio[833]: info: No code Jul 21 18:24:22 primo-kitchen volumio[833]: info: CoreStateMachine::pushState Jul 21 18:24:22 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:22 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioPushState Jul 21 18:24:22 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output update for this device Jul 21 18:24:22 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output Jul 21 18:24:22 primo-kitchen volumio[833]: info: CoreCommandRouter::servicePushState Jul 21 18:24:22 primo-kitchen volumio[833]: info: CoreStateMachine::pushState Jul 21 18:24:22 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:22 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioPushState Jul 21 18:24:22 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output update for this device Jul 21 18:24:22 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output Jul 21 18:24:22 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:22 primo-kitchen volumio[833]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jul 21 18:24:22 primo-kitchen volumio[833]: verbose: CURRENT POSITION 0 Jul 21 18:24:22 primo-kitchen volumio[833]: info: CoreStateMachine::syncState stateService stop Jul 21 18:24:22 primo-kitchen volumio[833]: info: CoreStateMachine::syncState currentStatus stop Jul 21 18:24:22 primo-kitchen volumio[833]: info: CoreStateMachine::pushState Jul 21 18:24:22 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:22 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioPushState Jul 21 18:24:22 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output update for this device Jul 21 18:24:22 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output Jul 21 18:24:22 primo-kitchen volumio[833]: info: No code Jul 21 18:24:22 primo-kitchen volumio[833]: info: CoreStateMachine::pushState Jul 21 18:24:22 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:22 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioPushState Jul 21 18:24:22 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output update for this device Jul 21 18:24:22 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output Jul 21 18:24:23 primo-kitchen systemd[1]: systemd-fsckd.service: Succeeded. Jul 21 18:24:23 primo-kitchen sudo[1633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jul 21 18:24:23 primo-kitchen sudo[1633]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:23 primo-kitchen volumiobt[1567]: pulseaudio: no process found Jul 21 18:24:23 primo-kitchen sudo[1633]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:23 primo-kitchen dbus-daemon[537]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.21' (uid=1000 pid=1644 comm="/usr/bin/pulseaudio --high-priority=true ") Jul 21 18:24:23 primo-kitchen systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jul 21 18:24:23 primo-kitchen kernel: IPVS: Creating netns size=1496 id=3 Jul 21 18:24:23 primo-kitchen kernel: IPVS: ftp: loaded support on port[0] = 21 Jul 21 18:24:23 primo-kitchen dbus-daemon[537]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jul 21 18:24:23 primo-kitchen systemd[1]: Started RealtimeKit Scheduling Policy Service. Jul 21 18:24:23 primo-kitchen rtkit-daemon[1645]: Successfully called chroot. Jul 21 18:24:23 primo-kitchen rtkit-daemon[1645]: Successfully dropped privileges. Jul 21 18:24:23 primo-kitchen rtkit-daemon[1645]: Successfully limited resources. Jul 21 18:24:23 primo-kitchen rtkit-daemon[1645]: Running. Jul 21 18:24:23 primo-kitchen rtkit-daemon[1645]: Watchdog thread running. Jul 21 18:24:23 primo-kitchen rtkit-daemon[1645]: Canary thread running. Jul 21 18:24:23 primo-kitchen volumio[833]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 21 18:24:23 primo-kitchen dbus-daemon[537]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.22' (uid=0 pid=1645 comm="/usr/lib/rtkit/rtkit-daemon ") Jul 21 18:24:23 primo-kitchen systemd[1]: Starting Authorization Manager... Jul 21 18:24:23 primo-kitchen volumio[833]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 21 18:24:23 primo-kitchen polkitd[1649]: started daemon version 0.105 using authority implementation `local' version `0.105' Jul 21 18:24:23 primo-kitchen dbus-daemon[537]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jul 21 18:24:23 primo-kitchen systemd[1]: Started Authorization Manager. Jul 21 18:24:23 primo-kitchen pulseaudio[1644]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 21 18:24:23 primo-kitchen pulseaudio[1644]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 21 18:24:23 primo-kitchen volumiobt[1567]: Applying permissions Jul 21 18:24:23 primo-kitchen volumiobt[1567]: Setting BT discoverable and pairable Jul 21 18:24:23 primo-kitchen bluetoothd[582]: Endpoint registered: sender=:1.42 path=/MediaEndpoint/A2DPSource Jul 21 18:24:23 primo-kitchen bluetoothd[582]: Endpoint registered: sender=:1.42 path=/MediaEndpoint/A2DPSink Jul 21 18:24:23 primo-kitchen pulseaudio[1644]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Jul 21 18:24:23 primo-kitchen volumiobt[1567]: Agent registered Jul 21 18:24:23 primo-kitchen volumiobt[1567]: [bluetooth]# -e power on Jul 21 18:24:23 primo-kitchen volumiobt[1567]: Invalid command in menu main: -e Jul 21 18:24:23 primo-kitchen volumiobt[1567]: Jul 21 18:24:23 primo-kitchen volumiobt[1567]: Use "help" for a list of available commands in a menu. Jul 21 18:24:23 primo-kitchen volumiobt[1567]: Use "menu " if you want to enter any submenu. Jul 21 18:24:23 primo-kitchen volumiobt[1567]: Use "back" if you want to return to menu main. Jul 21 18:24:23 primo-kitchen volumiobt[1567]: [bluetooth]# agent on Jul 21 18:24:23 primo-kitchen volumiobt[1567]: Agent is already registered Jul 21 18:24:23 primo-kitchen volumiobt[1567]: [bluetooth]# discoverable on Jul 21 18:24:23 primo-kitchen volumiobt[1567]: [bluetooth]# pairable on Jul 21 18:24:23 primo-kitchen volumiobt[1567]: [bluetooth]# agent NoInputNoOutput Jul 21 18:24:23 primo-kitchen volumiobt[1567]: Agent is already registered Jul 21 18:24:23 primo-kitchen volumiobt[1567]: [bluetooth]# default-agent Jul 21 18:24:23 primo-kitchen volumiobt[1567]: [bluetooth]# quit Jul 21 18:24:23 primo-kitchen volumiobt[1567]: [59B blob data] Jul 21 18:24:24 primo-kitchen volumio[833]: info: MRS: Getting audio outputs on start Jul 21 18:24:24 primo-kitchen volumio[833]: info: MRS: Requesting all other devices output Jul 21 18:24:25 primo-kitchen systemd[1]: systemd-hostnamed.service: Succeeded. Jul 21 18:24:25 primo-kitchen volumio[833]: info: TidalConnect service started! Jul 21 18:24:25 primo-kitchen volumio[833]: info: BOOT COMPLETED Jul 21 18:24:25 primo-kitchen volumio[833]: [Metrics] CommandRouter: 28s 548.36ms Jul 21 18:24:25 primo-kitchen volumio[833]: info: CoreCommandRouter::volumiosetStartupVolume Jul 21 18:24:25 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:25 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 18:24:25 primo-kitchen volumio[833]: info: CoreCommandRouter::Close All Modals sent Jul 21 18:24:25 primo-kitchen volumio[833]: info: CoreCommandRouter::Close All Modals sent Jul 21 18:24:26 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 21 18:24:26 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 21 18:24:26 primo-kitchen volumio[833]: xcb_connection_has_error() returned true Jul 21 18:24:26 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 21 18:24:26 primo-kitchen volumio[833]: info: Clearing queue after UPNP request Jul 21 18:24:26 primo-kitchen volumio[833]: info: CoreStateMachine::ClearQueue Jul 21 18:24:26 primo-kitchen volumio[833]: info: CoreStateMachine::stop Jul 21 18:24:26 primo-kitchen volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 18:24:26 primo-kitchen volumio[833]: info: CorePlayQueue::clearPlayQueue Jul 21 18:24:26 primo-kitchen volumio[833]: info: CorePlayQueue::saveQueue Jul 21 18:24:26 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioPushState Jul 21 18:24:26 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output update for this device Jul 21 18:24:26 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output Jul 21 18:24:26 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioPushQueue Jul 21 18:24:26 primo-kitchen volumio[833]: error: Upnp client error: Error: This socket has been ended by the other party Jul 21 18:24:28 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 21 18:24:28 primo-kitchen volumio[833]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Jul 21 18:24:28 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioGetState Jul 21 18:24:28 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:28 primo-kitchen volumio[833]: info: Starting UPNP Playback Jul 21 18:24:28 primo-kitchen volumio[833]: info: Preparing playback through UPNP Jul 21 18:24:28 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioGetState Jul 21 18:24:28 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:28 primo-kitchen volumio[833]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 21 18:24:29 primo-kitchen volumio[833]: info: Jul 21 18:24:29 primo-kitchen volumio[833]: ---------------------------- MPD announces system playlist update Jul 21 18:24:29 primo-kitchen volumio[833]: info: Ignoring MPD Status Update Jul 21 18:24:29 primo-kitchen volumio[833]: info: ------------------------------ 3ms Jul 21 18:24:29 primo-kitchen volumio[833]: info: Jul 21 18:24:29 primo-kitchen volumio[833]: ---------------------------- MPD announces system playlist update Jul 21 18:24:29 primo-kitchen volumio[833]: info: Ignoring MPD Status Update Jul 21 18:24:29 primo-kitchen volumio[833]: info: ------------------------------ 1ms Jul 21 18:24:29 primo-kitchen volumio[833]: info: Jul 21 18:24:29 primo-kitchen volumio[833]: ---------------------------- MPD announces system playlist update Jul 21 18:24:29 primo-kitchen volumio[833]: info: Ignoring MPD Status Update Jul 21 18:24:29 primo-kitchen volumio[833]: info: ------------------------------ 1ms Jul 21 18:24:29 primo-kitchen volumio[833]: info: Jul 21 18:24:29 primo-kitchen volumio[833]: ---------------------------- MPD announces system playlist update Jul 21 18:24:29 primo-kitchen volumio[833]: info: Ignoring MPD Status Update Jul 21 18:24:29 primo-kitchen volumio[833]: info: Jul 21 18:24:29 primo-kitchen volumio[833]: ---------------------------- MPD announces system playlist update Jul 21 18:24:29 primo-kitchen volumio[833]: info: Ignoring MPD Status Update Jul 21 18:24:29 primo-kitchen volumio[833]: info: ------------------------------ 2ms Jul 21 18:24:29 primo-kitchen volumio[833]: info: ------------------------------ 1ms Jul 21 18:24:29 primo-kitchen volumio[833]: info: Jul 21 18:24:29 primo-kitchen volumio[833]: ---------------------------- MPD announces system playlist update Jul 21 18:24:29 primo-kitchen volumio[833]: info: Ignoring MPD Status Update Jul 21 18:24:29 primo-kitchen volumio[833]: info: ------------------------------ 1ms Jul 21 18:24:31 primo-kitchen volumio[833]: info: Jul 21 18:24:31 primo-kitchen volumio[833]: ---------------------------- MPD announces state update: player Jul 21 18:24:31 primo-kitchen volumio[833]: info: ControllerMpd::getState Jul 21 18:24:31 primo-kitchen volumio[833]: verbose: ControllerMpd::sendMpdCommand status Jul 21 18:24:31 primo-kitchen volumio[833]: info: Jul 21 18:24:31 primo-kitchen volumio[833]: ---------------------------- MPD announces state update: player Jul 21 18:24:31 primo-kitchen volumio[833]: info: sendMpdCommand status took 8 milliseconds Jul 21 18:24:31 primo-kitchen volumio[833]: info: ControllerMpd::getState Jul 21 18:24:31 primo-kitchen volumio[833]: verbose: ControllerMpd::sendMpdCommand status Jul 21 18:24:31 primo-kitchen volumio[833]: verbose: ControllerMpd::parseState Jul 21 18:24:31 primo-kitchen volumio[833]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 18:24:31 primo-kitchen volumio[833]: info: sendMpdCommand status took 2 milliseconds Jul 21 18:24:31 primo-kitchen volumio[833]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 21 18:24:31 primo-kitchen volumio[833]: verbose: ControllerMpd::parseState Jul 21 18:24:31 primo-kitchen volumio[833]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 18:24:31 primo-kitchen volumio[833]: verbose: ControllerMpd::parseTrackInfo Jul 21 18:24:31 primo-kitchen volumio[833]: info: ControllerMpd::pushState Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreCommandRouter::servicePushState Jul 21 18:24:31 primo-kitchen volumio[833]: verbose: In UPNP mode Jul 21 18:24:31 primo-kitchen volumio[833]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"swyh-rs","artist":null,"album":null,"uri":"http://192.168.1.68:5901/stream/swyh.raw","trackType":"raw"} Jul 21 18:24:31 primo-kitchen volumio[833]: verbose: CURRENT POSITION 0 Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreStateMachine::syncState stateService play Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreStateMachine::syncState currentStatus stop Jul 21 18:24:31 primo-kitchen volumio[833]: info: ------------------------------ 15ms Jul 21 18:24:31 primo-kitchen volumio[833]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 21 18:24:31 primo-kitchen volumio[833]: verbose: ControllerMpd::parseTrackInfo Jul 21 18:24:31 primo-kitchen volumio[833]: info: ControllerMpd::pushState Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreCommandRouter::servicePushState Jul 21 18:24:31 primo-kitchen volumio[833]: verbose: In UPNP mode Jul 21 18:24:31 primo-kitchen volumio[833]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"swyh-rs","artist":null,"album":null,"uri":"http://192.168.1.68:5901/stream/swyh.raw","trackType":"raw"} Jul 21 18:24:31 primo-kitchen volumio[833]: verbose: CURRENT POSITION 0 Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreStateMachine::syncState stateService play Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreStateMachine::syncState currentStatus play Jul 21 18:24:31 primo-kitchen volumio[833]: info: Received an update from plugin. extracting info from payload Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreStateMachine::pushState Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioPushState Jul 21 18:24:31 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output update for this device Jul 21 18:24:31 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreStateMachine::pushState Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioPushState Jul 21 18:24:31 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output update for this device Jul 21 18:24:31 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output Jul 21 18:24:31 primo-kitchen volumio[833]: info: ------------------------------ 29ms Jul 21 18:24:31 primo-kitchen volumio[833]: info: Clearing queue after UPNP request Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreStateMachine::ClearQueue Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreStateMachine::stop Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreStateMachine::stPlaybackTimer Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreStateMachine::updateTrackBlock Jul 21 18:24:31 primo-kitchen volumio[833]: info: CorePlayQueue::getTrackBlock Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreStateMachine::pushState Jul 21 18:24:31 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioPushState Jul 21 18:24:31 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output update for this device Jul 21 18:24:31 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreStateMachine::serviceStop Jul 21 18:24:31 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:31 primo-kitchen volumio[833]: info: ControllerMpd::stop Jul 21 18:24:31 primo-kitchen volumio[833]: verbose: ControllerMpd::sendMpdCommand stop Jul 21 18:24:31 primo-kitchen volumio[833]: info: CorePlayQueue::clearPlayQueue Jul 21 18:24:31 primo-kitchen volumio[833]: info: CorePlayQueue::saveQueue Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioPushState Jul 21 18:24:31 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output update for this device Jul 21 18:24:31 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioPushQueue Jul 21 18:24:31 primo-kitchen volumio[833]: info: Jul 21 18:24:31 primo-kitchen volumio[833]: ---------------------------- MPD announces state update: player Jul 21 18:24:31 primo-kitchen volumio[833]: info: sendMpdCommand stop took 27 milliseconds Jul 21 18:24:31 primo-kitchen volumio[833]: info: ControllerMpd::getState Jul 21 18:24:31 primo-kitchen volumio[833]: verbose: ControllerMpd::sendMpdCommand status Jul 21 18:24:31 primo-kitchen volumio[833]: info: Jul 21 18:24:31 primo-kitchen volumio[833]: ---------------------------- MPD announces system playlist update Jul 21 18:24:31 primo-kitchen volumio[833]: info: Ignoring MPD Status Update Jul 21 18:24:31 primo-kitchen volumio[833]: info: sendMpdCommand status took 2 milliseconds Jul 21 18:24:31 primo-kitchen volumio[833]: verbose: ControllerMpd::parseState Jul 21 18:24:31 primo-kitchen volumio[833]: info: ControllerMpd::pushState Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreCommandRouter::servicePushState Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreStateMachine::pushState Jul 21 18:24:31 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioPushState Jul 21 18:24:31 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output update for this device Jul 21 18:24:31 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output Jul 21 18:24:31 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:31 primo-kitchen volumio[833]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 21 18:24:31 primo-kitchen volumio[833]: verbose: CURRENT POSITION 0 Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreStateMachine::syncState stateService stop Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreStateMachine::syncState currentStatus stop Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreStateMachine::pushState Jul 21 18:24:31 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioPushState Jul 21 18:24:31 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output update for this device Jul 21 18:24:31 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output Jul 21 18:24:31 primo-kitchen volumio[833]: info: No code Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreStateMachine::pushState Jul 21 18:24:31 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioPushState Jul 21 18:24:31 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output update for this device Jul 21 18:24:31 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output Jul 21 18:24:31 primo-kitchen volumio[833]: info: ------------------------------ 41ms Jul 21 18:24:31 primo-kitchen volumio[833]: error: updateQueue error: null Jul 21 18:24:31 primo-kitchen volumio[833]: info: ------------------------------ 46ms Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 18:24:31 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 21 18:24:32 primo-kitchen volumio[833]: info: Clearing queue after UPNP request Jul 21 18:24:32 primo-kitchen volumio-remote-updater[549]: No test mode Jul 21 18:24:32 primo-kitchen volumio-remote-updater[549]: No alpha test mode Jul 21 18:24:32 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 21 18:24:32 primo-kitchen volumio[833]: info: CoreStateMachine::ClearQueue Jul 21 18:24:32 primo-kitchen volumio[833]: info: CoreStateMachine::stop Jul 21 18:24:32 primo-kitchen volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 18:24:32 primo-kitchen volumio[833]: info: CorePlayQueue::clearPlayQueue Jul 21 18:24:32 primo-kitchen volumio[833]: info: CorePlayQueue::saveQueue Jul 21 18:24:32 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioPushState Jul 21 18:24:32 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output update for this device Jul 21 18:24:32 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output Jul 21 18:24:32 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioPushQueue Jul 21 18:24:32 primo-kitchen volumio[833]: info: Jul 21 18:24:32 primo-kitchen volumio[833]: ---------------------------- MPD announces system playlist update Jul 21 18:24:32 primo-kitchen volumio[833]: info: Ignoring MPD Status Update Jul 21 18:24:32 primo-kitchen volumio[833]: error: updateQueue error: null Jul 21 18:24:32 primo-kitchen volumio[833]: info: ------------------------------ 2ms Jul 21 18:24:32 primo-kitchen volumio[833]: info: Starting UPNP Playback Jul 21 18:24:32 primo-kitchen volumio[833]: info: Preparing playback through UPNP Jul 21 18:24:32 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioGetState Jul 21 18:24:32 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:32 primo-kitchen volumio[833]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 21 18:24:32 primo-kitchen volumio[833]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 21 18:24:32 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 21 18:24:32 primo-kitchen volumio[833]: info: Jul 21 18:24:32 primo-kitchen volumio[833]: ---------------------------- MPD announces system playlist update Jul 21 18:24:32 primo-kitchen volumio[833]: info: Ignoring MPD Status Update Jul 21 18:24:32 primo-kitchen volumio[833]: info: ------------------------------ 1ms Jul 21 18:24:32 primo-kitchen volumio[833]: info: Jul 21 18:24:32 primo-kitchen volumio[833]: ---------------------------- MPD announces system playlist update Jul 21 18:24:32 primo-kitchen volumio[833]: info: Ignoring MPD Status Update Jul 21 18:24:32 primo-kitchen volumio[833]: info: ------------------------------ 1ms Jul 21 18:24:32 primo-kitchen volumio[833]: info: Jul 21 18:24:32 primo-kitchen volumio[833]: ---------------------------- MPD announces system playlist update Jul 21 18:24:32 primo-kitchen volumio[833]: info: Ignoring MPD Status Update Jul 21 18:24:32 primo-kitchen volumio[833]: info: ------------------------------ 1ms Jul 21 18:24:32 primo-kitchen volumio[833]: info: Jul 21 18:24:32 primo-kitchen volumio[833]: ---------------------------- MPD announces system playlist update Jul 21 18:24:32 primo-kitchen volumio[833]: info: Ignoring MPD Status Update Jul 21 18:24:32 primo-kitchen volumio[833]: info: ------------------------------ 1ms Jul 21 18:24:32 primo-kitchen volumio[833]: info: Jul 21 18:24:32 primo-kitchen volumio[833]: ---------------------------- MPD announces system playlist update Jul 21 18:24:32 primo-kitchen volumio[833]: info: Ignoring MPD Status Update Jul 21 18:24:32 primo-kitchen volumio[833]: info: ------------------------------ 1ms Jul 21 18:24:32 primo-kitchen volumio[833]: info: Jul 21 18:24:32 primo-kitchen volumio[833]: ---------------------------- MPD announces system playlist update Jul 21 18:24:32 primo-kitchen volumio[833]: info: Ignoring MPD Status Update Jul 21 18:24:32 primo-kitchen volumio[833]: info: ------------------------------ 1ms Jul 21 18:24:33 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:33 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 18:24:33 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 21 18:24:33 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 21 18:24:33 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 21 18:24:33 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioGetBrowseSources Jul 21 18:24:33 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 21 18:24:34 primo-kitchen volumio[833]: info: Jul 21 18:24:34 primo-kitchen volumio[833]: ---------------------------- MPD announces state update: player Jul 21 18:24:34 primo-kitchen volumio[833]: info: ControllerMpd::getState Jul 21 18:24:34 primo-kitchen volumio[833]: verbose: ControllerMpd::sendMpdCommand status Jul 21 18:24:34 primo-kitchen volumio[833]: info: Jul 21 18:24:34 primo-kitchen volumio[833]: ---------------------------- MPD announces state update: player Jul 21 18:24:34 primo-kitchen volumio[833]: info: sendMpdCommand status took 3 milliseconds Jul 21 18:24:34 primo-kitchen volumio[833]: info: ControllerMpd::getState Jul 21 18:24:34 primo-kitchen volumio[833]: verbose: ControllerMpd::sendMpdCommand status Jul 21 18:24:34 primo-kitchen volumio[833]: verbose: ControllerMpd::parseState Jul 21 18:24:34 primo-kitchen volumio[833]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 18:24:34 primo-kitchen volumio[833]: info: sendMpdCommand status took 1 milliseconds Jul 21 18:24:34 primo-kitchen volumio[833]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 21 18:24:34 primo-kitchen volumio[833]: verbose: ControllerMpd::parseState Jul 21 18:24:34 primo-kitchen volumio[833]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 18:24:34 primo-kitchen volumio[833]: verbose: ControllerMpd::parseTrackInfo Jul 21 18:24:34 primo-kitchen volumio[833]: info: ControllerMpd::pushState Jul 21 18:24:34 primo-kitchen volumio[833]: info: CoreCommandRouter::servicePushState Jul 21 18:24:34 primo-kitchen volumio[833]: verbose: In UPNP mode Jul 21 18:24:34 primo-kitchen volumio[833]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"swyh-rs","artist":null,"album":null,"uri":"http://192.168.1.68:5901/stream/swyh.raw","trackType":"raw"} Jul 21 18:24:34 primo-kitchen volumio[833]: verbose: CURRENT POSITION 0 Jul 21 18:24:34 primo-kitchen volumio[833]: info: CoreStateMachine::syncState stateService play Jul 21 18:24:34 primo-kitchen volumio[833]: info: CoreStateMachine::syncState currentStatus stop Jul 21 18:24:34 primo-kitchen volumio[833]: info: ------------------------------ 8ms Jul 21 18:24:34 primo-kitchen volumio[833]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 21 18:24:34 primo-kitchen volumio[833]: verbose: ControllerMpd::parseTrackInfo Jul 21 18:24:34 primo-kitchen volumio[833]: info: ControllerMpd::pushState Jul 21 18:24:34 primo-kitchen volumio[833]: info: CoreCommandRouter::servicePushState Jul 21 18:24:34 primo-kitchen volumio[833]: verbose: In UPNP mode Jul 21 18:24:34 primo-kitchen volumio[833]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"swyh-rs","artist":null,"album":null,"uri":"http://192.168.1.68:5901/stream/swyh.raw","trackType":"raw"} Jul 21 18:24:34 primo-kitchen volumio[833]: verbose: CURRENT POSITION 0 Jul 21 18:24:34 primo-kitchen volumio[833]: info: CoreStateMachine::syncState stateService play Jul 21 18:24:34 primo-kitchen volumio[833]: info: CoreStateMachine::syncState currentStatus play Jul 21 18:24:34 primo-kitchen volumio[833]: info: Received an update from plugin. extracting info from payload Jul 21 18:24:34 primo-kitchen volumio[833]: info: CoreStateMachine::pushState Jul 21 18:24:34 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 18:24:34 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioPushState Jul 21 18:24:34 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output update for this device Jul 21 18:24:34 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output Jul 21 18:24:34 primo-kitchen volumio[833]: info: CoreStateMachine::pushState Jul 21 18:24:34 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioPushState Jul 21 18:24:34 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output update for this device Jul 21 18:24:34 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output Jul 21 18:24:34 primo-kitchen volumio[833]: info: ------------------------------ 28ms Jul 21 18:24:37 primo-kitchen volumio[833]: info: Executing endpoint metavolumio Jul 21 18:24:37 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 21 18:24:38 primo-kitchen volumio[833]: info: Jul 21 18:24:38 primo-kitchen volumio[833]: ---------------------------- MPD announces system playlist update Jul 21 18:24:38 primo-kitchen volumio[833]: info: Ignoring MPD Status Update Jul 21 18:24:38 primo-kitchen volumio[833]: info: Jul 21 18:24:38 primo-kitchen volumio[833]: ---------------------------- MPD announces state update: player Jul 21 18:24:38 primo-kitchen volumio[833]: info: ControllerMpd::getState Jul 21 18:24:38 primo-kitchen volumio[833]: verbose: ControllerMpd::sendMpdCommand status Jul 21 18:24:38 primo-kitchen volumio[833]: info: ------------------------------ 3ms Jul 21 18:24:38 primo-kitchen volumio[833]: info: sendMpdCommand status took 1 milliseconds Jul 21 18:24:38 primo-kitchen volumio[833]: verbose: ControllerMpd::parseState Jul 21 18:24:38 primo-kitchen volumio[833]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 18:24:38 primo-kitchen volumio[833]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 21 18:24:38 primo-kitchen volumio[833]: verbose: ControllerMpd::parseTrackInfo Jul 21 18:24:38 primo-kitchen volumio[833]: info: ControllerMpd::pushState Jul 21 18:24:38 primo-kitchen volumio[833]: info: CoreCommandRouter::servicePushState Jul 21 18:24:38 primo-kitchen volumio[833]: verbose: In UPNP mode Jul 21 18:24:38 primo-kitchen volumio[833]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1219,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"swyh.raw","artist":"swyh-rs","album":null,"uri":"http://192.168.1.68:5901/stream/swyh.raw","trackType":"raw"} Jul 21 18:24:38 primo-kitchen volumio[833]: verbose: CURRENT POSITION 0 Jul 21 18:24:38 primo-kitchen volumio[833]: info: CoreStateMachine::syncState stateService play Jul 21 18:24:38 primo-kitchen volumio[833]: info: CoreStateMachine::syncState currentStatus play Jul 21 18:24:38 primo-kitchen volumio[833]: info: Received an update from plugin. extracting info from payload Jul 21 18:24:38 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 21 18:24:38 primo-kitchen volumio[833]: info: CoreStateMachine::pushState Jul 21 18:24:38 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:24:38 primo-kitchen volumio[833]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jul 21 18:24:38 primo-kitchen volumio[833]: info: ------------------------------ 6ms Jul 21 18:24:43 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Jul 21 18:24:43 primo-kitchen volumio[833]: info: MRS: Setting this device as multiroomSync server Jul 21 18:24:43 primo-kitchen volumio[833]: info: MRS: Setting another device as multiroomSync client Jul 21 18:24:43 primo-kitchen volumio[833]: info: MRS: Setting this device as Server Jul 21 18:24:43 primo-kitchen volumio[833]: info: Jul 21 18:24:43 primo-kitchen volumio[833]: [1721543083990] ---------------------------- MRS: Setting Multiroom Server Jul 21 18:24:43 primo-kitchen volumio[833]: info: MRS: Pushing multiroomSync output Jul 21 18:24:43 primo-kitchen volumio[833]: info: Enabled audio output: e17865ee-3475-454d-9c0b-6ece07f3c16b Jul 21 18:24:44 primo-kitchen volumio[833]: info: MRS: STARTING SNAPCLIENT Jul 21 18:24:44 primo-kitchen volumio[833]: info: MRS: Updating multiroomSync output Jul 21 18:24:44 primo-kitchen volumio[833]: info: MRS: STOPPING SNAPCLIENT Jul 21 18:24:44 primo-kitchen sudo[1690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioSnapclient Jul 21 18:24:44 primo-kitchen volumio[833]: info: MRS: Updating multiroomSync output Jul 21 18:24:44 primo-kitchen sudo[1690]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:44 primo-kitchen systemd[1]: Starting Snapcast client... Jul 21 18:24:44 primo-kitchen volumio[833]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 21 18:24:44 primo-kitchen snapclient[1697]: daemon started Jul 21 18:24:44 primo-kitchen systemd[1]: Started Snapcast client. Jul 21 18:24:44 primo-kitchen sudo[1690]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:44 primo-kitchen volumio[833]: info: MRS: SNAPCLIENT STARTED Jul 21 18:24:44 primo-kitchen volumio[833]: info: MRS: STARTING SNAPSERVER Jul 21 18:24:44 primo-kitchen volumio[833]: info: MRS: enable multiroom server output Jul 21 18:24:44 primo-kitchen volumio[833]: info: MRS: Set multiroom target PCM to volumioMultiRoom Jul 21 18:24:44 primo-kitchen volumio[833]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Jul 21 18:24:44 primo-kitchen volumio[833]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Jul 21 18:24:44 primo-kitchen volumio[833]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Jul 21 18:24:44 primo-kitchen volumio[833]: info: MRS: Snap server restart Jul 21 18:24:44 primo-kitchen sudo[1704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioSnapserver Jul 21 18:24:44 primo-kitchen sudo[1704]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:24:44 primo-kitchen systemd[1]: Starting Snapcast server... Jul 21 18:24:44 primo-kitchen volumio[833]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 21 18:24:44 primo-kitchen snapserver[1710]: Settings file: "/var/lib/snapserver/server.json" Jul 21 18:24:44 primo-kitchen snapserver[1710]: pipe:///tmp/multiroom/server/fifo?name=Radio&sampleformat=48000:16:2&codec=flac Jul 21 18:24:44 primo-kitchen snapserver[1710]: 2024-07-21 18-24-44 [Notice] Settings file: "/var/lib/snapserver/server.json" Jul 21 18:24:44 primo-kitchen snapserver[1711]: daemon started Jul 21 18:24:44 primo-kitchen systemd[1]: Started Snapcast server. Jul 21 18:24:44 primo-kitchen sudo[1704]: pam_unix(sudo:session): session closed for user root Jul 21 18:24:44 primo-kitchen volumio[833]: info: MRS: SNAPSERVER STARTED Jul 21 18:24:45 primo-kitchen snapclient[1697]: Connected to 192.168.1.50 Jul 21 18:24:45 primo-kitchen snapserver[1711]: StreamServer::NewConnection: ::ffff:192.168.1.50 Jul 21 18:24:46 primo-kitchen volumio[833]: info: MRS: Updating multiroomSync output Jul 21 18:24:46 primo-kitchen volumio[833]: info: MRS: Updating multiroomSync output Jul 21 18:24:46 primo-kitchen volumio[833]: info: MRS: Updating multiroomSync output Jul 21 18:24:46 primo-kitchen volumio[833]: info: MRS: Updating multiroomSync output Jul 21 18:24:47 primo-kitchen snapserver[1711]: StreamServer::NewConnection: ::ffff:192.168.1.100 Jul 21 18:24:47 primo-kitchen snapserver[1711]: Exception in StreamSession::reader(): read_some: End of file Jul 21 18:24:53 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Jul 21 18:24:53 primo-kitchen volumio[833]: info: MRS: Setting this device as multiroomSync server Jul 21 18:24:53 primo-kitchen volumio[833]: info: MRS: Setting another device as multiroomSync client Jul 21 18:24:53 primo-kitchen volumio[833]: info: MRS: Updating multiroomSync output Jul 21 18:24:53 primo-kitchen volumio[833]: info: MRS: Updating multiroomSync output Jul 21 18:24:53 primo-kitchen volumiologrotate[553]: ls: cannot access '/var/log/samba/log.wb-PRIMO': No such file or directory Jul 21 18:24:53 primo-kitchen volumiologrotate[553]: ls: cannot access 'KITCHEN': No such file or directory Jul 21 18:24:55 primo-kitchen volumio[833]: info: MRS: Updating multiroomSync output Jul 21 18:24:55 primo-kitchen volumio[833]: info: MRS: Updating multiroomSync output Jul 21 18:24:55 primo-kitchen volumio[833]: info: MRS: Updating multiroomSync output Jul 21 18:24:55 primo-kitchen volumio[833]: info: MRS: Updating multiroomSync output Jul 21 18:24:56 primo-kitchen snapserver[1711]: StreamServer::NewConnection: ::ffff:192.168.1.100 Jul 21 18:24:57 primo-kitchen snapserver[1711]: Exception in StreamSession::reader(): read_some: End of file Jul 21 18:25:00 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Jul 21 18:25:00 primo-kitchen volumio[833]: info: MRS: Setting this device as multiroomSync server Jul 21 18:25:00 primo-kitchen volumio[833]: info: MRS: Setting another device as multiroomSync client Jul 21 18:25:00 primo-kitchen volumio[833]: info: MRS: Updating multiroomSync output Jul 21 18:25:00 primo-kitchen volumio[833]: info: MRS: Updating multiroomSync output Jul 21 18:25:02 primo-kitchen volumio[833]: info: MRS: Updating multiroomSync output Jul 21 18:25:02 primo-kitchen volumio[833]: info: MRS: Updating multiroomSync output Jul 21 18:25:02 primo-kitchen volumio[833]: info: MRS: Updating multiroomSync output Jul 21 18:25:02 primo-kitchen volumio[833]: info: MRS: Updating multiroomSync output Jul 21 18:25:03 primo-kitchen sudo[1776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 21 18:25:03 primo-kitchen sudo[1778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 21 18:25:03 primo-kitchen sudo[1776]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:25:03 primo-kitchen sudo[1778]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 18:25:03 primo-kitchen sudo[1776]: pam_unix(sudo:session): session closed for user root Jul 21 18:25:03 primo-kitchen sudo[1778]: pam_unix(sudo:session): session closed for user root Jul 21 18:25:03 primo-kitchen volumio[833]: verbose: New Socket.io Connection to 192.168.1.50 from 192.168.1.68 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jul 21 18:25:03 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 21 18:25:03 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioGetVisibleSources Jul 21 18:25:03 primo-kitchen volumio[833]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 21 18:25:03 primo-kitchen volumio[833]: info: CoreCommandRouter::volumioGetState Jul 21 18:25:03 primo-kitchen volumio[833]: info: CorePlayQueue::getTrack 0 Jul 21 18:25:03 primo-kitchen volumio[833]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 21 18:25:03 primo-kitchen volumio[833]: TypeError: Cannot read property 'name' of undefined Jul 21 18:25:03 primo-kitchen volumio[833]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jul 21 18:25:03 primo-kitchen volumio[833]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Jul 21 18:25:03 primo-kitchen volumio[833]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:55:38) Jul 21 18:25:03 primo-kitchen volumio[833]: at Socket.emit (events.js:400:28) Jul 21 18:25:03 primo-kitchen volumio[833]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 21 18:25:03 primo-kitchen volumio[833]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Jul 21 18:25:03 primo-kitchen volumio[833]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 21 18:25:03 primo-kitchen sudo[1805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-21 18:24 Jul 21 18:25:03 primo-kitchen sudo[1805]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:59:47 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="5afa2cba9d125c5d0103869143516973"