-- Logs begin at Wed 2024-08-14 04:37:42 UTC, end at Wed 2024-08-14 04:39:55 UTC. -- Aug 14 04:38:00 volumio volumio[4835]: info: Loading plugin "network"... Aug 14 04:38:00 volumio volumio[4835]: info: Refreshing Cached IP Addresses Aug 14 04:38:00 volumio sudo[4895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 14 04:38:00 volumio sudo[4895]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 04:38:00 volumio volumio[4835]: info: Loading plugin "services"... Aug 14 04:38:00 volumio sudo[4895]: pam_unix(sudo:session): session closed for user root Aug 14 04:38:00 volumio volumio[4835]: info: Loading plugin "alsa_controller"... Aug 14 04:38:01 volumio sudo[4898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 14 04:38:01 volumio sudo[4898]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 04:38:01 volumio sudo[4898]: pam_unix(sudo:session): session closed for user root Aug 14 04:38:01 volumio volumio[4835]: info: Loading plugin "wizard"... Aug 14 04:38:01 volumio volumio[4835]: info: Loading plugin "volumio_command_line_client"... Aug 14 04:38:01 volumio volumio[4835]: info: Loading plugin "upnp"... Aug 14 04:38:01 volumio volumio[4835]: info: [1723610281182] Starting Upmpd Daemon Aug 14 04:38:01 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 14 04:38:01 volumio volumio[4835]: info: Loading plugin "my_music"... Aug 14 04:38:01 volumio volumio[4835]: info: Loading plugin "mpd"... Aug 14 04:38:01 volumio volumio-remote-updater[4547]: [2024-08-14 04:38:01] [connect] Successful connection Aug 14 04:38:01 volumio volumio[4835]: info: Loading plugin "upnp_browser"... Aug 14 04:38:02 volumio volumio[4835]: info: Loading plugin "networkfs"... Aug 14 04:38:02 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 14 04:38:02 volumio volumio[4835]: info: Loading plugin "alarm-clock"... Aug 14 04:38:03 volumio volumio[4835]: info: Loading plugin "airplay_emulation"... Aug 14 04:38:03 volumio volumio[4835]: info: Starting Shairport Sync Aug 14 04:38:03 volumio volumio[4835]: info: Loading plugin "last_100"... Aug 14 04:38:03 volumio volumio[4835]: info: Loading plugin "webradio"... Aug 14 04:38:03 volumio kernel: libphy: wol_reg12[12]==0, error Aug 14 04:38:03 volumio kernel: libphy: stmmac-0:08 - Link is Down Aug 14 04:38:03 volumio dhcpcd[4453]: eth0: carrier lost Aug 14 04:38:03 volumio ifup[4429]: dhcpcd[4453]: eth0: carrier lost Aug 14 04:38:03 volumio volumio[4835]: info: Loading plugin "i2s_dacs"... Aug 14 04:38:03 volumio volumio[4835]: info: I2S DAC not set, start Auto-detection Aug 14 04:38:03 volumio volumio[4835]: info: Loading plugin "volumiodiscovery"... Aug 14 04:38:03 volumio volumio[4835]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 14 04:38:03 volumio node[4835]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 14 04:38:03 volumio node[4835]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 14 04:38:03 volumio node[4835]: *** WARNING *** For more information see Aug 14 04:38:03 volumio node[4835]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 14 04:38:03 volumio node[4835]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 14 04:38:03 volumio node[4835]: *** WARNING *** For more information see Aug 14 04:38:03 volumio volumio[4835]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 14 04:38:03 volumio volumio[4835]: *** WARNING *** For more information see Aug 14 04:38:03 volumio volumio[4835]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 14 04:38:03 volumio volumio[4835]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 14 04:38:03 volumio volumio[4835]: *** WARNING *** For more information see Aug 14 04:38:03 volumio volumio[4835]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 14 04:38:03 volumio volumio[4835]: Discovery: StartAdv! undefined Aug 14 04:38:03 volumio volumio[4835]: Discovery: Started advertising... Volumio - undefined Aug 14 04:38:03 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 14 04:38:03 volumio volumio[4835]: info: Loading plugin "youtube2"... Aug 14 04:38:03 volumio ifplugd(eth0)[4661]: Link beat lost. Aug 14 04:38:07 volumio kernel: libphy: negotiation is done set a3_config 0xa900 Aug 14 04:38:07 volumio kernel: libphy: a3_config=0xa900 Aug 14 04:38:07 volumio kernel: libphy: stmmac-0:08 - Link is Up - 100/Full Aug 14 04:38:07 volumio ifup[4429]: dhcpcd[4453]: eth0: carrier acquired Aug 14 04:38:07 volumio dhcpcd[4453]: eth0: carrier acquired Aug 14 04:38:07 volumio ifup[4429]: dhcpcd[4453]: eth0: soliciting a DHCP lease Aug 14 04:38:07 volumio dhcpcd[4453]: eth0: soliciting a DHCP lease Aug 14 04:38:08 volumio ifplugd(eth0)[4661]: Link beat detected. Aug 14 04:38:08 volumio volumio[4835]: info: Loading plugin "outputs"... Aug 14 04:38:08 volumio volumio[4835]: info: Loading plugin "albumart"... Aug 14 04:38:08 volumio volumio[4835]: info: Plugin example_plugin is not enabled Aug 14 04:38:08 volumio volumio[4835]: info: Loading plugin "inputs"... Aug 14 04:38:08 volumio volumio[4835]: info: Loading plugin "updater_comm"... Aug 14 04:38:09 volumio volumio[4835]: info: Plugin mpdemulation is not enabled Aug 14 04:38:09 volumio volumio[4835]: info: Loading plugin "rest_api"... Aug 14 04:38:09 volumio volumio[4835]: info: Loading plugin "websocket"... Aug 14 04:38:09 volumio volumio[4835]: info: ___________ START PLUGINS ___________ Aug 14 04:38:09 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 14 04:38:09 volumio volumio[4835]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 14 04:38:09 volumio volumio[4835]: info: [1723610289146] CoreMusicLibrary::Adding element Media Servers Aug 14 04:38:09 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 14 04:38:09 volumio volumio[4835]: Forking 3 albumart workers Aug 14 04:38:09 volumio volumio[4835]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 14 04:38:09 volumio volumio[4835]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 14 04:38:09 volumio volumio[4835]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 14 04:38:09 volumio volumio[4835]: info: [1723610289427] CoreMusicLibrary::Adding element Last_100 Aug 14 04:38:09 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 14 04:38:09 volumio volumio[4835]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 14 04:38:09 volumio volumio[4835]: info: [1723610289435] CoreMusicLibrary::Adding element Webradio Aug 14 04:38:09 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 14 04:38:09 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 14 04:38:09 volumio volumio[4835]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 14 04:38:09 volumio volumio[4835]: info: [1723610289519] CoreMusicLibrary::Adding element YouTube2 Aug 14 04:38:09 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 14 04:38:09 volumio volumio[4835]: info: ------------------------------------------- Aug 14 04:38:09 volumio volumio[4835]: info: ----- MyVolumio plugins startup ---- Aug 14 04:38:09 volumio volumio[4835]: info: ------------------------------------------- Aug 14 04:38:09 volumio volumio[4835]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 14 04:38:09 volumio volumio[4835]: info: Loading i18n strings for locale vi Aug 14 04:38:09 volumio volumio[4835]: Updating browse sources language Aug 14 04:38:09 volumio volumio[4835]: Cannot find translation for sourceMedia Servers Aug 14 04:38:09 volumio volumio[4835]: Cannot find translation for sourceYouTube2 Aug 14 04:38:09 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 14 04:38:09 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 14 04:38:09 volumio volumio[4835]: info: CoreCommandRouter::initPlayerControls Aug 14 04:38:09 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 04:38:09 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Aug 14 04:38:09 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 04:38:09 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 04:38:09 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 04:38:09 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 04:38:09 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 04:38:09 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 14 04:38:09 volumio volumio[4835]: info: BOOT COMPLETED Aug 14 04:38:09 volumio volumio[4835]: [Metrics] CommandRouter: 12s 288.58ms Aug 14 04:38:09 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 04:38:10 volumio volumio[4835]: Starting albumart workers Aug 14 04:38:10 volumio volumio[4835]: Starting albumart workers Aug 14 04:38:10 volumio volumio[4835]: Starting albumart workers Aug 14 04:38:13 volumio dhcpcd[4453]: eth0: offered 192.168.1.7 from 192.168.1.1 Aug 14 04:38:13 volumio ifup[4429]: dhcpcd[4453]: eth0: offered 192.168.1.7 from 192.168.1.1 Aug 14 04:38:13 volumio dhcpcd[4453]: eth0: leased 192.168.1.7 for 3600 seconds Aug 14 04:38:13 volumio dhcpcd[4453]: eth0: adding host route to 192.168.1.7 via 127.0.0.1 Aug 14 04:38:13 volumio dhcpcd[4453]: eth0: adding route to 192.168.1.0/24 Aug 14 04:38:13 volumio dhcpcd[4453]: eth0: adding default route via 192.168.1.1 Aug 14 04:38:13 volumio avahi-daemon[4669]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.7. Aug 14 04:38:13 volumio avahi-daemon[4669]: New relevant interface eth0.IPv4 for mDNS. Aug 14 04:38:13 volumio avahi-daemon[4669]: Registering new address record for 192.168.1.7 on eth0.IPv4. Aug 14 04:38:13 volumio ifup[4429]: dhcpcd[4453]: eth0: leased 192.168.1.7 for 3600 seconds Aug 14 04:38:13 volumio ifup[4429]: dhcpcd[4453]: eth0: adding host route to 192.168.1.7 via 127.0.0.1 Aug 14 04:38:13 volumio ifup[4429]: dhcpcd[4453]: eth0: adding route to 192.168.1.0/24 Aug 14 04:38:13 volumio ifup[4429]: dhcpcd[4453]: eth0: adding default route via 192.168.1.1 Aug 14 04:38:13 volumio dhcpcd[4453]: forked to background, child pid 5020 Aug 14 04:38:13 volumio ifup[4429]: dhcpcd[4453]: forked to background, child pid 5020 Aug 14 04:38:13 volumio systemd[1]: Reloading OpenBSD Secure Shell server. Aug 14 04:38:13 volumio sshd[4680]: Received SIGHUP; restarting. Aug 14 04:38:13 volumio systemd[1]: Reloaded OpenBSD Secure Shell server. Aug 14 04:38:13 volumio sshd[4680]: Server listening on 0.0.0.0 port 22. Aug 14 04:38:13 volumio sshd[4680]: Server listening on :: port 22. Aug 14 04:38:14 volumio ntpd[4666]: Listen normally on 4 eth0 192.168.1.7 UDP 123 Aug 14 04:38:14 volumio ntpd[4666]: peers refreshed Aug 14 04:38:15 volumio volumio[4835]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Aug 14 04:38:15 volumio volumio[4835]: info: CoreCommandRouter::Close All Modals sent Aug 14 04:38:15 volumio volumio[4835]: info: CoreCommandRouter::Close All Modals sent Aug 14 04:38:15 volumio volumio[4835]: Express server listening on port 3000 Aug 14 04:38:15 volumio volumio[4835]: [Metrics] WebUI: 18s 807.61ms Aug 14 04:38:15 volumio volumio[4835]: Volumio Calling Home Aug 14 04:38:15 volumio volumio[4835]: info: CoreStateMachine::resetVolumioState Aug 14 04:38:15 volumio volumio[4835]: info: CoreStateMachine::getcurrentVolume Aug 14 04:38:15 volumio volumio[4835]: info: CoreCommandRouter::volumioRetrievevolume Aug 14 04:38:15 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 04:38:15 volumio sudo[5109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 14 04:38:15 volumio sudo[5109]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 04:38:15 volumio sudo[5109]: pam_unix(sudo:session): session closed for user root Aug 14 04:38:15 volumio sudo[5112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 14 04:38:15 volumio sudo[5112]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 04:38:15 volumio sudo[5112]: pam_unix(sudo:session): session closed for user root Aug 14 04:38:15 volumio volumio[4835]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Aug 14 04:38:15 volumio volumio[4835]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Aug 14 04:38:15 volumio volumio[4835]: info: Setting Device type: Amlogic Aug 14 04:38:15 volumio volumio[4835]: info: MPD running with PID4670 Aug 14 04:38:15 volumio volumio[4835]: ,establishing connection Aug 14 04:38:15 volumio sudo[5116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Aug 14 04:38:15 volumio sudo[5116]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 04:38:15 volumio sudo[5116]: pam_unix(sudo:session): session closed for user root Aug 14 04:38:15 volumio volumio[4835]: info: VolumeController:: Volume=undefined Mute =false Aug 14 04:38:15 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:38:15 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:38:15 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:38:15 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:38:15 volumio volumio[4835]: info: CoreStateMachine::updateTrackBlock Aug 14 04:38:15 volumio volumio[4835]: info: CorePlayQueue::getTrackBlock Aug 14 04:38:15 volumio volumio[4835]: info: CoreCommandRouter::volumioRetrievevolume Aug 14 04:38:16 volumio volumio[4835]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Aug 14 04:38:16 volumio volumio[4835]: Error: Could not open file `/dev/i2c-1' or `/dev/i2c/1': No such file or directory Aug 14 04:38:16 volumio volumio[4835]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 14 04:38:16 volumio volumio[4835]: info: [youtube2-auth] Refreshing access token... Aug 14 04:38:16 volumio volumio[4835]: info: Reloading queue from file Aug 14 04:38:16 volumio volumio[4835]: error: updateQueue error: null Aug 14 04:38:16 volumio volumio[4835]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Aug 14 04:38:16 volumio volumio[4835]: info: CoreStateMachine::setRepeat true single undefined Aug 14 04:38:16 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:38:16 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:38:16 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:38:16 volumio volumio-remote-updater[4547]: [2024-08-14 04:38:16] [connect] Successful connection Aug 14 04:38:16 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:38:16 volumio volumio[4835]: info: CoreStateMachine::setRandom false Aug 14 04:38:16 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:38:16 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:38:16 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:38:16 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:38:16 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 14 04:38:16 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 14 04:38:16 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Aug 14 04:38:16 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 14 04:38:16 volumio volumio[4835]: info: VolumeController:: Volume=undefined Mute =false Aug 14 04:38:16 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:38:16 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:38:16 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:38:16 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:38:16 volumio volumio[4835]: info: Starting Shairport Sync Aug 14 04:38:16 volumio volumio[4835]: info: Starting Shairport Sync Aug 14 04:38:16 volumio sudo[5126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 14 04:38:16 volumio sudo[5126]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 04:38:16 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Aug 14 04:38:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 14 04:38:16 volumio sudo[5129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 14 04:38:16 volumio sudo[5129]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 04:38:16 volumio sudo[5126]: pam_unix(sudo:session): session closed for user root Aug 14 04:38:16 volumio volumio-remote-updater[4547]: [2024-08-14 04:38:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1723610296 101 Aug 14 04:38:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 14 04:38:16 volumio sudo[5132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 14 04:38:16 volumio sudo[5132]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 04:38:16 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Aug 14 04:38:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 14 04:38:16 volumio sudo[5129]: pam_unix(sudo:session): session closed for user root Aug 14 04:38:16 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Aug 14 04:38:16 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Aug 14 04:38:16 volumio sudo[5132]: pam_unix(sudo:session): session closed for user root Aug 14 04:38:16 volumio volumio[4835]: info: Shairport-Sync Started Aug 14 04:38:16 volumio volumio[4835]: Error adding Membership: Error: addMembership EINVAL Aug 14 04:38:16 volumio volumio[4835]: info: Shairport-Sync Started Aug 14 04:38:16 volumio volumio[4835]: info: Upmpdcli Daemon Started Aug 14 04:38:39 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 14 04:38:39 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 04:38:44 volumio volumio[4835]: Discovery: adding 2d84f9c2-8eae-4c25-a1ce-8d7f4ea52fc6 Aug 14 04:38:44 volumio volumio[4835]: info: mDNS: Found device Volumio Aug 14 04:38:44 volumio volumio[4835]: info: CoreCommandRouter::volumioGetState Aug 14 04:38:44 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:06 volumio volumio[4835]: info: MPD Database updated - AlbumList cache refreshed Aug 14 04:39:06 volumio volumio[4835]: info: Aug 14 04:39:06 volumio volumio[4835]: ---------------------------- MPD announces state update: database Aug 14 04:39:06 volumio volumio[4835]: verbose: ControllerMpd::sendMpdCommand status Aug 14 04:39:06 volumio volumio[4835]: info: Aug 14 04:39:06 volumio volumio[4835]: ---------------------------- MPD announces state update: update Aug 14 04:39:06 volumio volumio[4835]: info: ControllerMpd::getState Aug 14 04:39:06 volumio volumio[4835]: verbose: ControllerMpd::sendMpdCommand status Aug 14 04:39:06 volumio volumio[4835]: info: ControllerMpd::getState Aug 14 04:39:06 volumio volumio[4835]: verbose: ControllerMpd::sendMpdCommand status Aug 14 04:39:06 volumio volumio[4835]: verbose: ControllerMpd::parseState Aug 14 04:39:06 volumio volumio[4835]: info: Command Router : Notfying DB Updatetrue Aug 14 04:39:06 volumio volumio[4835]: verbose: ControllerMpd::parseState Aug 14 04:39:06 volumio volumio[4835]: verbose: ControllerMpd::parseState Aug 14 04:39:06 volumio volumio[4835]: info: ControllerMpd::pushState Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::servicePushState Aug 14 04:39:06 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:39:06 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:39:06 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:06 volumio volumio[4835]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 14 04:39:06 volumio volumio[4835]: verbose: CURRENT POSITION 0 Aug 14 04:39:06 volumio volumio[4835]: info: CoreStateMachine::syncState stateService stop Aug 14 04:39:06 volumio volumio[4835]: info: CoreStateMachine::syncState currentStatus stop Aug 14 04:39:06 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:39:06 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:39:06 volumio volumio[4835]: info: No code Aug 14 04:39:06 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:39:06 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:39:06 volumio volumio[4835]: info: ControllerMpd::pushState Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::servicePushState Aug 14 04:39:06 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:39:06 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:39:06 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:06 volumio volumio[4835]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 14 04:39:06 volumio volumio[4835]: verbose: CURRENT POSITION 0 Aug 14 04:39:06 volumio volumio[4835]: info: CoreStateMachine::syncState stateService stop Aug 14 04:39:06 volumio volumio[4835]: info: CoreStateMachine::syncState currentStatus stop Aug 14 04:39:06 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:39:06 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:39:06 volumio volumio[4835]: info: No code Aug 14 04:39:06 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:39:06 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:39:06 volumio volumio[4835]: info: ------------------------------ 150ms Aug 14 04:39:06 volumio volumio[4835]: info: ------------------------------ 149ms Aug 14 04:39:06 volumio volumio[4835]: verbose: ControllerMpd::sendMpdCommand status Aug 14 04:39:06 volumio volumio[4835]: info: Aug 14 04:39:06 volumio volumio[4835]: ---------------------------- MPD announces state update: update Aug 14 04:39:06 volumio volumio[4835]: info: ControllerMpd::getState Aug 14 04:39:06 volumio volumio[4835]: verbose: ControllerMpd::sendMpdCommand status Aug 14 04:39:06 volumio volumio[4835]: verbose: ControllerMpd::sendMpdCommand status Aug 14 04:39:06 volumio volumio[4835]: info: Aug 14 04:39:06 volumio volumio[4835]: ---------------------------- MPD announces state update: update Aug 14 04:39:06 volumio volumio[4835]: info: ControllerMpd::getState Aug 14 04:39:06 volumio volumio[4835]: verbose: ControllerMpd::sendMpdCommand status Aug 14 04:39:06 volumio volumio[4835]: verbose: ControllerMpd::parseState Aug 14 04:39:06 volumio volumio[4835]: info: Command Router : Notfying DB Updatetrue Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::Close All Modals sent Aug 14 04:39:06 volumio volumio[4835]: verbose: ControllerMpd::parseState Aug 14 04:39:06 volumio volumio[4835]: info: ControllerMpd::pushState Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::servicePushState Aug 14 04:39:06 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:39:06 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:39:06 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:06 volumio volumio[4835]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 14 04:39:06 volumio volumio[4835]: verbose: CURRENT POSITION 0 Aug 14 04:39:06 volumio volumio[4835]: info: CoreStateMachine::syncState stateService stop Aug 14 04:39:06 volumio volumio[4835]: info: CoreStateMachine::syncState currentStatus stop Aug 14 04:39:06 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:39:06 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:39:06 volumio volumio[4835]: info: No code Aug 14 04:39:06 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:39:06 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:39:06 volumio volumio[4835]: info: ------------------------------ 88ms Aug 14 04:39:06 volumio volumio[4835]: verbose: ControllerMpd::parseState Aug 14 04:39:06 volumio volumio[4835]: info: Command Router : Notfying DB Updatetrue Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::Close All Modals sent Aug 14 04:39:06 volumio volumio[4835]: verbose: ControllerMpd::parseState Aug 14 04:39:06 volumio volumio[4835]: info: ControllerMpd::pushState Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::servicePushState Aug 14 04:39:06 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:39:06 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:39:06 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:06 volumio volumio[4835]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 14 04:39:06 volumio volumio[4835]: verbose: CURRENT POSITION 0 Aug 14 04:39:06 volumio volumio[4835]: info: CoreStateMachine::syncState stateService stop Aug 14 04:39:06 volumio volumio[4835]: info: CoreStateMachine::syncState currentStatus stop Aug 14 04:39:06 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:39:06 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:39:06 volumio volumio[4835]: info: No code Aug 14 04:39:06 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:39:06 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:39:06 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:39:06 volumio volumio[4835]: info: ------------------------------ 153ms Aug 14 04:39:06 volumio volumio[4835]: verbose: ControllerMpd::sendMpdCommand status Aug 14 04:39:06 volumio volumio[4835]: info: Aug 14 04:39:06 volumio volumio[4835]: ---------------------------- MPD announces state update: update Aug 14 04:39:06 volumio volumio[4835]: info: ControllerMpd::getState Aug 14 04:39:06 volumio volumio[4835]: verbose: ControllerMpd::sendMpdCommand status Aug 14 04:39:06 volumio volumio[4835]: verbose: ControllerMpd::sendMpdCommand status Aug 14 04:39:06 volumio volumio[4835]: info: Aug 14 04:39:06 volumio volumio[4835]: ---------------------------- MPD announces state update: update Aug 14 04:39:06 volumio volumio[4835]: info: ControllerMpd::getState Aug 14 04:39:06 volumio volumio[4835]: verbose: ControllerMpd::sendMpdCommand status Aug 14 04:39:06 volumio volumio[4835]: verbose: ControllerMpd::parseState Aug 14 04:39:07 volumio volumio[4835]: info: Command Router : Notfying DB Updatefalse Aug 14 04:39:07 volumio volumio[4835]: info: CoreCommandRouter::Close All Modals sent Aug 14 04:39:07 volumio volumio[4835]: verbose: ControllerMpd::parseState Aug 14 04:39:07 volumio volumio[4835]: info: ControllerMpd::pushState Aug 14 04:39:07 volumio volumio[4835]: info: CoreCommandRouter::servicePushState Aug 14 04:39:07 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:39:07 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:07 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:39:07 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:39:07 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:07 volumio volumio[4835]: 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} Aug 14 04:39:07 volumio volumio[4835]: verbose: CURRENT POSITION 0 Aug 14 04:39:07 volumio volumio[4835]: info: CoreStateMachine::syncState stateService stop Aug 14 04:39:07 volumio volumio[4835]: info: CoreStateMachine::syncState currentStatus stop Aug 14 04:39:07 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:39:07 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:07 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:39:07 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:39:07 volumio volumio[4835]: info: No code Aug 14 04:39:07 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:39:07 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:07 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:39:07 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:39:07 volumio volumio[4835]: info: ------------------------------ 101ms Aug 14 04:39:07 volumio volumio[4835]: verbose: ControllerMpd::parseState Aug 14 04:39:07 volumio volumio[4835]: info: Command Router : Notfying DB Updatefalse Aug 14 04:39:07 volumio volumio[4835]: info: CoreCommandRouter::Close All Modals sent Aug 14 04:39:07 volumio volumio[4835]: verbose: ControllerMpd::parseState Aug 14 04:39:07 volumio volumio[4835]: info: ControllerMpd::pushState Aug 14 04:39:07 volumio volumio[4835]: info: CoreCommandRouter::servicePushState Aug 14 04:39:07 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:39:07 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:07 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:39:07 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:39:07 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:07 volumio volumio[4835]: 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} Aug 14 04:39:07 volumio volumio[4835]: verbose: CURRENT POSITION 0 Aug 14 04:39:07 volumio volumio[4835]: info: CoreStateMachine::syncState stateService stop Aug 14 04:39:07 volumio volumio[4835]: info: CoreStateMachine::syncState currentStatus stop Aug 14 04:39:07 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:39:07 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:07 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:39:07 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:39:07 volumio volumio[4835]: info: No code Aug 14 04:39:07 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:39:07 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:07 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:39:07 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:39:07 volumio volumio[4835]: info: ------------------------------ 165ms Aug 14 04:39:11 volumio systemd[1]: Job dev-disk-by\x2dlabel-BOOT.device/start timed out. Aug 14 04:39:11 volumio systemd[1]: Timed out waiting for device dev-disk-by\x2dlabel-BOOT.device. Aug 14 04:39:11 volumio systemd[1]: Dependency failed for File System Check on /dev/disk/by-label/BOOT. Aug 14 04:39:11 volumio systemd[1]: Startup finished in 11.185s (kernel) + 1min 30.426s (userspace) = 1min 41.611s. Aug 14 04:39:11 volumio volumio[4835]: verbose: ControllerMpd::sendMpdCommand status Aug 14 04:39:11 volumio volumio[4835]: info: Aug 14 04:39:11 volumio volumio[4835]: ---------------------------- MPD announces state update: update Aug 14 04:39:11 volumio volumio[4835]: info: ControllerMpd::getState Aug 14 04:39:11 volumio volumio[4835]: verbose: ControllerMpd::sendMpdCommand status Aug 14 04:39:11 volumio volumio[4835]: verbose: ControllerMpd::sendMpdCommand status Aug 14 04:39:11 volumio volumio[4835]: info: Aug 14 04:39:11 volumio volumio[4835]: ---------------------------- MPD announces state update: update Aug 14 04:39:11 volumio volumio[4835]: info: ControllerMpd::getState Aug 14 04:39:11 volumio volumio[4835]: verbose: ControllerMpd::sendMpdCommand status Aug 14 04:39:11 volumio volumio[4835]: verbose: ControllerMpd::parseState Aug 14 04:39:11 volumio volumio[4835]: info: Command Router : Notfying DB Updatefalse Aug 14 04:39:11 volumio volumio[4835]: info: CoreCommandRouter::Close All Modals sent Aug 14 04:39:11 volumio volumio[4835]: verbose: ControllerMpd::parseState Aug 14 04:39:11 volumio volumio[4835]: info: ControllerMpd::pushState Aug 14 04:39:11 volumio volumio[4835]: info: CoreCommandRouter::servicePushState Aug 14 04:39:11 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:39:11 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:11 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:39:11 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:39:11 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:11 volumio volumio[4835]: 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} Aug 14 04:39:11 volumio volumio[4835]: verbose: CURRENT POSITION 0 Aug 14 04:39:11 volumio volumio[4835]: info: CoreStateMachine::syncState stateService stop Aug 14 04:39:11 volumio volumio[4835]: info: CoreStateMachine::syncState currentStatus stop Aug 14 04:39:11 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:39:11 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:11 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:39:11 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:39:11 volumio volumio[4835]: info: No code Aug 14 04:39:11 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:39:11 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:11 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:39:11 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:39:11 volumio volumio[4835]: info: ------------------------------ 86ms Aug 14 04:39:11 volumio volumio[4835]: verbose: ControllerMpd::parseState Aug 14 04:39:11 volumio volumio[4835]: info: Command Router : Notfying DB Updatefalse Aug 14 04:39:11 volumio volumio[4835]: info: CoreCommandRouter::Close All Modals sent Aug 14 04:39:11 volumio volumio[4835]: verbose: ControllerMpd::parseState Aug 14 04:39:11 volumio volumio[4835]: info: ControllerMpd::pushState Aug 14 04:39:11 volumio volumio[4835]: info: CoreCommandRouter::servicePushState Aug 14 04:39:11 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:39:11 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:11 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:39:11 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:39:11 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:11 volumio volumio[4835]: 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} Aug 14 04:39:11 volumio volumio[4835]: verbose: CURRENT POSITION 0 Aug 14 04:39:11 volumio volumio[4835]: info: CoreStateMachine::syncState stateService stop Aug 14 04:39:11 volumio volumio[4835]: info: CoreStateMachine::syncState currentStatus stop Aug 14 04:39:11 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:39:11 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:11 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:39:11 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:39:11 volumio volumio[4835]: info: No code Aug 14 04:39:11 volumio volumio[4835]: info: CoreStateMachine::pushState Aug 14 04:39:11 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:11 volumio volumio[4835]: info: CoreCommandRouter::volumioPushState Aug 14 04:39:11 volumio volumio[4835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 04:39:11 volumio volumio[4835]: info: ------------------------------ 153ms Aug 14 04:39:12 volumio volumio[4835]: info: CoreCommandRouter::volumioGetState Aug 14 04:39:12 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:12 volumio ntpd_intres[4741]: host name not found: 0.debian.pool.ntp.org Aug 14 04:39:12 volumio volumio[4835]: Discovery: this is already registered, 2d84f9c2-8eae-4c25-a1ce-8d7f4ea52fc6 Aug 14 04:39:12 volumio volumio[4835]: info: mDNS: Found device Volumio Aug 14 04:39:12 volumio volumio[4835]: info: CoreCommandRouter::volumioGetState Aug 14 04:39:12 volumio volumio[4835]: info: CorePlayQueue::getTrack 0 Aug 14 04:39:13 volumio volumio[4835]: error: [youtube2-auth] Failed to refresh access token: Error: getaddrinfo ENOTFOUND www.googleapis.com www.googleapis.com:443 Aug 14 04:39:13 volumio volumio[4835]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 14 04:39:13 volumio volumio[4835]: info: [youtube2-auth] Starting auth flow... Aug 14 04:39:13 volumio volumio[4835]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 14 04:39:22 volumio volumio[4835]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=aml9xxxarmv7&variante=volumio&version=2.857&uuid=362fd595ff0544790486159cc00d3fd2" http://updates.volumio.org:7070/downloader-v1/track-device Aug 14 04:39:22 volumio volumio[4835]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 14 04:39:22 volumio volumio[4835]: Dload Upload Total Spent Left Speed Aug 14 04:39:22 volumio volumio[4835]: [1.9K blob data] Aug 14 04:39:22 volumio volumio[4835]: [1.9K blob data] Aug 14 04:39:22 volumio volumio[4835]: [460B blob data] Aug 14 04:39:22 volumio volumio[4835]: retrying in 5 seconds, trial 0 Aug 14 04:39:22 volumio volumio[4835]: Volumio Calling Home Aug 14 04:39:54 volumio ntpd_intres[4741]: DNS 1.debian.pool.ntp.org -> 203.113.174.44 Aug 14 04:39:54 volumio ntpd_intres[4741]: DNS 2.debian.pool.ntp.org -> 203.113.174.44 Aug 14 04:39:54 volumio ntpd_intres[4741]: DNS 3.debian.pool.ntp.org -> 203.113.174.44 Aug 14 04:39:54 volumio volumio[4835]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 14 04:39:54 volumio volumio[4835]: ReferenceError: deferred is not defined Aug 14 04:39:54 volumio volumio[4835]: at ClientRequest.req.on (/data/plugins/music_service/youtube2/lib/core/gapi/auth.js:157:13) Aug 14 04:39:54 volumio volumio[4835]: at emitOne (events.js:116:13) Aug 14 04:39:54 volumio volumio[4835]: at ClientRequest.emit (events.js:211:7) Aug 14 04:39:54 volumio volumio[4835]: at TLSSocket.socketErrorListener (_http_client.js:387:9) Aug 14 04:39:54 volumio volumio[4835]: at emitOne (events.js:116:13) Aug 14 04:39:54 volumio volumio[4835]: at TLSSocket.emit (events.js:211:7) Aug 14 04:39:54 volumio volumio[4835]: at emitErrorNT (internal/streams/destroy.js:64:8) Aug 14 04:39:54 volumio volumio[4835]: at _combinedTickCallback (internal/process/next_tick.js:138:11) Aug 14 04:39:54 volumio volumio[4835]: at process._tickCallback (internal/process/next_tick.js:180:9) Aug 14 04:39:54 volumio volumio[4835]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 14 04:39:55 volumio sudo[5513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-14 04:38 Aug 14 04:39:55 volumio sudo[5513]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="168ba7444df98cd868db72f24faec0b6346489cc" VOLUMIO_FE_VERSION="847a48ecf32d35cc502a0053d585d1a69236e391" VOLUMIO_BE_VERSION="99dfb342fe07851f1e26bd1d8046d0234eda91dd" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 11 15:53:56 CET 2020" VOLUMIO_VERSION="2.857" VOLUMIO_HARDWARE="aml9xxxarmv7" VOLUMIO_HASH="5e595833ee6075a27fef1ba35f928abb"