-- 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"