-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Sun 2025-06-29 15:12:38 CST. -- Jun 29 15:11:00 volumio kernel: hwmon hwmon1: Voltage normalised Jun 29 15:11:00 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 29 15:11:00 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 29 15:11:00 volumio volumio[2559]: info: Discovery: Getting this device information Jun 29 15:11:00 volumio volumio[2559]: info: CoreCommandRouter::volumioGetState Jun 29 15:11:00 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:00 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 29 15:11:02 volumio nmbd[704]: [2025/06/29 15:11:02.066772, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 29 15:11:02 volumio nmbd[704]: query_name_response: Multiple (2) responses received for a query on subnet 172.16.0.219 for name WORKGROUP<1d>. Jun 29 15:11:02 volumio nmbd[704]: This response was from IP 172.16.0.2, reporting an IP address of 172.16.0.2. Jun 29 15:11:02 volumio nmbd[704]: [2025/06/29 15:11:02.068402, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 29 15:11:02 volumio nmbd[704]: query_name_response: Multiple (2) responses received for a query on subnet 172.16.0.228 for name WORKGROUP<1d>. Jun 29 15:11:02 volumio nmbd[704]: This response was from IP 172.16.0.2, reporting an IP address of 172.16.0.2. Jun 29 15:11:04 volumio volumio[2559]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jun 29 15:11:04 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 29 15:11:04 volumio volumio[2559]: info: Preparing to save Alsa Options, stopping services first Jun 29 15:11:04 volumio volumio[2559]: info: CoreCommandRouter::volumioGetState Jun 29 15:11:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPause Jun 29 15:11:04 volumio volumio[2559]: info: CoreStateMachine::pause Jun 29 15:11:04 volumio volumio[2559]: info: Saving Audio Output to: {"output_device":{"value":"3","label":"Cyberdrive Audio Driver"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Jun 29 15:11:04 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 29 15:11:05 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 29 15:11:05 volumio volumio[2559]: info: Setting mixer Cyberdrive Audio Driver Output Playback Swi for card Cyberdrive Audio Driver Jun 29 15:11:05 volumio volumio[2559]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 29 15:11:05 volumio volumio[2559]: info: Updating Volume Controller Parameters: Device: 3 Name: Cyberdrive Audio Driver Mixer: Cyberdrive Audio Driver Output Playback Swi Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 29 15:11:05 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 29 15:11:05 volumio volumio[2559]: info: Disabling external Volume Control Jun 29 15:11:05 volumio volumio[2559]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 29 15:11:05 volumio volumio[2559]: info: Preparing to generate the ALSA configuration file Jun 29 15:11:05 volumio volumio[2559]: Unable to parse: Jun 29 15:11:05 volumio volumio[2559]: Simple mixer control 'Cyberdrive Audio Driver Output Playback Swi',0 Jun 29 15:11:05 volumio volumio[2559]: Capabilities: pswitch Jun 29 15:11:05 volumio volumio[2559]: Playback channels: Front Left - Front Right Jun 29 15:11:05 volumio volumio[2559]: Mono: Jun 29 15:11:05 volumio volumio[2559]: Front Left: Playback [on] Jun 29 15:11:05 volumio volumio[2559]: Front Right: Playback [on] Jun 29 15:11:05 volumio volumio[2559]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jun 29 15:11:05 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 29 15:11:05 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 29 15:11:05 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 29 15:11:05 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 29 15:11:05 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 29 15:11:05 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 29 15:11:05 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 29 15:11:05 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 29 15:11:05 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 29 15:11:05 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 29 15:11:05 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 29 15:11:05 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 29 15:11:05 volumio volumio[2559]: Unable to parse: Jun 29 15:11:05 volumio volumio[2559]: Simple mixer control 'Cyberdrive Audio Driver Output Playback Swi',0 Jun 29 15:11:05 volumio volumio[2559]: Capabilities: pswitch Jun 29 15:11:05 volumio volumio[2559]: Playback channels: Front Left - Front Right Jun 29 15:11:05 volumio volumio[2559]: Mono: Jun 29 15:11:05 volumio volumio[2559]: Front Left: Playback [on] Jun 29 15:11:05 volumio volumio[2559]: Front Right: Playback [on] Jun 29 15:11:05 volumio volumio[2559]: info: VolumeController:: Volume=undefined Mute =false Jun 29 15:11:05 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:05 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:05 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:11:05 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:05 volumio volumio[2559]: info: Asound.conf file written Jun 29 15:11:05 volumio sudo[3034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 29 15:11:05 volumio sudo[3034]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 15:11:05 volumio sudo[3034]: pam_unix(sudo:session): session closed for user root Jun 29 15:11:05 volumio volumio[2559]: No state is present for card b1 Jun 29 15:11:05 volumio volumio[2559]: /usr/share/alsa/init/bcm2835:14: control element not found Jun 29 15:11:05 volumio volumio[2559]: No state is present for card b1 Jun 29 15:11:05 volumio volumio[2559]: No state is present for card Headphones Jun 29 15:11:05 volumio volumio[2559]: /usr/share/alsa/init/bcm2835:20: control element not found Jun 29 15:11:05 volumio volumio[2559]: No state is present for card Headphones Jun 29 15:11:05 volumio volumio[2559]: No state is present for card player Jun 29 15:11:05 volumio volumio[2559]: Found hardware: "USB-Audio" "USB Mixer" "USB1fc9:0001" "" "" Jun 29 15:11:05 volumio volumio[2559]: Hardware is initialized using a generic method Jun 29 15:11:05 volumio volumio[2559]: No state is present for card player Jun 29 15:11:05 volumio volumio[2559]: No state is present for card Driver Jun 29 15:11:05 volumio volumio[2559]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:305d" "" "" Jun 29 15:11:05 volumio volumio[2559]: Hardware is initialized using a generic method Jun 29 15:11:05 volumio volumio[2559]: No state is present for card Driver Jun 29 15:11:05 volumio volumio[2559]: No state is present for card UP4 Jun 29 15:11:05 volumio volumio[2559]: Found hardware: "USB-Audio" "USB Mixer" "USB0a12:1244" "" "" Jun 29 15:11:05 volumio volumio[2559]: Hardware is initialized using a generic method Jun 29 15:11:05 volumio volumio[2559]: No state is present for card UP4 Jun 29 15:11:05 volumio volumio[2559]: info: Output device has changed, restarting MPD Jun 29 15:11:05 volumio volumio[2559]: info: Output device has changed, restarting Shairport Sync Jun 29 15:11:05 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:05 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 29 15:11:05 volumio sudo[3039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 29 15:11:05 volumio sudo[3039]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 15:11:05 volumio sudo[3039]: pam_unix(sudo:session): session closed for user root Jun 29 15:11:05 volumio sudo[3041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 29 15:11:05 volumio sudo[3041]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 15:11:05 volumio systemd[1]: Stopping Music Player Daemon... Jun 29 15:11:05 volumio volumio[2559]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 29 15:11:05 volumio volumio[2559]: info: Output device has changed, restarting MPD Jun 29 15:11:05 volumio systemd[1]: mpd.service: Succeeded. Jun 29 15:11:05 volumio systemd[1]: Stopped Music Player Daemon. Jun 29 15:11:06 volumio volumio[2559]: info: Output device has changed, restarting Shairport Sync Jun 29 15:11:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 29 15:11:06 volumio sudo[3049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 29 15:11:06 volumio sudo[3049]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 15:11:06 volumio sudo[3049]: pam_unix(sudo:session): session closed for user root Jun 29 15:11:06 volumio sudo[3051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 29 15:11:06 volumio sudo[3051]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 15:11:06 volumio systemd[1]: Starting Music Player Daemon... Jun 29 15:11:06 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 29 15:11:06 volumio systemd[1]: mpd.service: Succeeded. Jun 29 15:11:06 volumio systemd[1]: Stopped Music Player Daemon. Jun 29 15:11:06 volumio volumio[2559]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 29 15:11:06 volumio systemd[1]: Starting Music Player Daemon... Jun 29 15:11:06 volumio volumio[2559]: info: MPD Permissions set Jun 29 15:11:06 volumio volumio[2559]: info: MPD Permissions set Jun 29 15:11:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 29 15:11:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 29 15:11:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:06 volumio volumio[2559]: info: Starting Shairport Sync Jun 29 15:11:06 volumio volumio[2559]: info: Starting Shairport Sync Jun 29 15:11:06 volumio sudo[3068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 29 15:11:06 volumio sudo[3068]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 15:11:06 volumio sudo[3058]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 29 15:11:06 volumio sudo[3058]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 15:11:06 volumio sudo[3070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 29 15:11:06 volumio sudo[3070]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 15:11:06 volumio sudo[3058]: pam_unix(sudo:session): session closed for user root Jun 29 15:11:06 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 29 15:11:06 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 29 15:11:06 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 29 15:11:06 volumio kernel: hwmon hwmon1: Undervoltage detected! Jun 29 15:11:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 29 15:11:06 volumio sudo[3068]: pam_unix(sudo:session): session closed for user root Jun 29 15:11:06 volumio sudo[3070]: pam_unix(sudo:session): session closed for user root Jun 29 15:11:06 volumio volumio[2559]: info: Shairport-Sync Started Jun 29 15:11:06 volumio volumio[2559]: info: Shairport-Sync Started Jun 29 15:11:08 volumio mpd[3074]: Jun 29 15:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 29 15:11:08 volumio systemd[1]: Started Music Player Daemon. Jun 29 15:11:08 volumio sudo[3051]: pam_unix(sudo:session): session closed for user root Jun 29 15:11:08 volumio sudo[3041]: pam_unix(sudo:session): session closed for user root Jun 29 15:11:08 volumio volumio[2559]: error: MPD error: The expression evaluated to a falsy value: Jun 29 15:11:08 volumio volumio[2559]: assert.ok(self.idling) Jun 29 15:11:08 volumio volumio[2559]: error: The expression evaluated to a falsy value: Jun 29 15:11:08 volumio volumio[2559]: assert.ok(self.idling) Jun 29 15:11:08 volumio volumio[2559]: error: updateQueue error: null Jun 29 15:11:09 volumio volumio[2559]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 29 15:11:09 volumio volumio[2559]: info: CoreStateMachine::getcurrentVolume Jun 29 15:11:09 volumio volumio[2559]: info: CoreCommandRouter::volumioRetrievevolume Jun 29 15:11:09 volumio volumio[2559]: Unable to parse: Jun 29 15:11:09 volumio volumio[2559]: Simple mixer control 'Cyberdrive Audio Driver Output Playback Swi',0 Jun 29 15:11:09 volumio volumio[2559]: Capabilities: pswitch Jun 29 15:11:09 volumio volumio[2559]: Playback channels: Front Left - Front Right Jun 29 15:11:09 volumio volumio[2559]: Mono: Jun 29 15:11:09 volumio volumio[2559]: Front Left: Playback [on] Jun 29 15:11:09 volumio volumio[2559]: Front Right: Playback [on] Jun 29 15:11:09 volumio volumio[2559]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jun 29 15:11:09 volumio volumio[2559]: Unable to parse: Jun 29 15:11:09 volumio volumio[2559]: Simple mixer control 'Cyberdrive Audio Driver Output Playback Swi',0 Jun 29 15:11:09 volumio volumio[2559]: Capabilities: pswitch Jun 29 15:11:09 volumio volumio[2559]: Playback channels: Front Left - Front Right Jun 29 15:11:09 volumio volumio[2559]: Mono: Jun 29 15:11:09 volumio volumio[2559]: Front Left: Playback [on] Jun 29 15:11:09 volumio volumio[2559]: Front Right: Playback [on] Jun 29 15:11:09 volumio volumio[2559]: info: VolumeController:: Volume=undefined Mute =false Jun 29 15:11:09 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:09 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:09 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:11:09 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:09 volumio volumio[2559]: info: CoreStateMachine::updateTrackBlock Jun 29 15:11:09 volumio volumio[2559]: info: CorePlayQueue::getTrackBlock Jun 29 15:11:09 volumio volumio[2559]: info: CoreCommandRouter::volumioRetrievevolume Jun 29 15:11:09 volumio volumio[2559]: Unable to parse: Jun 29 15:11:09 volumio volumio[2559]: Simple mixer control 'Cyberdrive Audio Driver Output Playback Swi',0 Jun 29 15:11:09 volumio volumio[2559]: Capabilities: pswitch Jun 29 15:11:09 volumio volumio[2559]: Playback channels: Front Left - Front Right Jun 29 15:11:09 volumio volumio[2559]: Mono: Jun 29 15:11:09 volumio volumio[2559]: Front Left: Playback [on] Jun 29 15:11:09 volumio volumio[2559]: Front Right: Playback [on] Jun 29 15:11:09 volumio volumio[2559]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jun 29 15:11:09 volumio volumio[2559]: Unable to parse: Jun 29 15:11:09 volumio volumio[2559]: Simple mixer control 'Cyberdrive Audio Driver Output Playback Swi',0 Jun 29 15:11:09 volumio volumio[2559]: Capabilities: pswitch Jun 29 15:11:09 volumio volumio[2559]: Playback channels: Front Left - Front Right Jun 29 15:11:09 volumio volumio[2559]: Mono: Jun 29 15:11:09 volumio volumio[2559]: Front Left: Playback [on] Jun 29 15:11:09 volumio volumio[2559]: Front Right: Playback [on] Jun 29 15:11:09 volumio volumio[2559]: info: VolumeController:: Volume=undefined Mute =false Jun 29 15:11:09 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:09 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:09 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:10 volumio kernel: hwmon hwmon1: Voltage normalised Jun 29 15:11:14 volumio kernel: hwmon hwmon1: Undervoltage detected! Jun 29 15:11:16 volumio volumio[2559]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 29 15:11:16 volumio volumio[2559]: info: Retrying Login Due to Network Error Jun 29 15:11:16 volumio volumio[2559]: info: CoreCommandRouter::volumioPlay Jun 29 15:11:16 volumio volumio[2559]: info: CoreStateMachine::play index undefined Jun 29 15:11:16 volumio volumio[2559]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 29 15:11:16 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:16 volumio volumio[2559]: info: CoreStateMachine::startPlaybackTimer Jun 29 15:11:16 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:16 volumio volumio[2559]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/昊轩京剧-吴昊 - 情字已瘦.flac Jun 29 15:11:16 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand stop Jun 29 15:11:16 volumio volumio[2559]: info: sendMpdCommand stop took 4 milliseconds Jun 29 15:11:16 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand clear Jun 29 15:11:16 volumio volumio[2559]: info: Jun 29 15:11:16 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:11:16 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:11:16 volumio volumio[2559]: info: sendMpdCommand clear took 5 milliseconds Jun 29 15:11:16 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/昊轩京剧-吴昊 - 情字已瘦.flac" Jun 29 15:11:16 volumio volumio[2559]: info: Jun 29 15:11:16 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:11:16 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:11:16 volumio volumio[2559]: error: updateQueue error: null Jun 29 15:11:16 volumio volumio[2559]: info: Jun 29 15:11:16 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:11:16 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:11:16 volumio volumio[2559]: info: ------------------------------ 15ms Jun 29 15:11:16 volumio volumio[2559]: info: sendMpdCommand add "INTERNAL/昊轩京剧-吴昊 - 情字已瘦.flac" took 10 milliseconds Jun 29 15:11:16 volumio volumio[2559]: info: ------------------------------ 9ms Jun 29 15:11:16 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand play Jun 29 15:11:16 volumio volumio[2559]: info: Jun 29 15:11:16 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:11:16 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:11:16 volumio volumio[2559]: info: Jun 29 15:11:16 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:11:16 volumio volumio[2559]: info: ------------------------------ 14ms Jun 29 15:11:16 volumio volumio[2559]: info: sendMpdCommand play took 9 milliseconds Jun 29 15:11:16 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:11:16 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:11:16 volumio volumio[2559]: info: Jun 29 15:11:16 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:11:16 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:11:16 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:11:16 volumio volumio[2559]: info: ------------------------------ 18ms Jun 29 15:11:16 volumio volumio[2559]: info: sendMpdCommand status took 33 milliseconds Jun 29 15:11:16 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:11:16 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:11:16 volumio volumio[2559]: info: Jun 29 15:11:16 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:11:16 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:11:16 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:11:16 volumio volumio[2559]: info: sendMpdCommand status took 41 milliseconds Jun 29 15:11:16 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 29 15:11:16 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:11:16 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:11:16 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:11:16 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:11:16 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:11:16 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:16 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":189,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"情字已瘦","artist":"昊轩京剧-吴昊","album":"情字已瘦","uri":"INTERNAL/昊轩京剧-吴昊 - 情字已瘦.flac","trackType":"flac"} Jun 29 15:11:16 volumio volumio[2559]: verbose: CURRENT POSITION 0 Jun 29 15:11:16 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:11:16 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus stop Jun 29 15:11:16 volumio volumio[2559]: info: ------------------------------ 75ms Jun 29 15:11:16 volumio volumio[2559]: info: Jun 29 15:11:16 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:11:16 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:11:16 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:11:16 volumio volumio[2559]: info: sendMpdCommand status took 33 milliseconds Jun 29 15:11:16 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 27 milliseconds Jun 29 15:11:16 volumio volumio[2559]: info: sendMpdCommand status took 6 milliseconds Jun 29 15:11:16 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:11:16 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:11:16 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:11:16 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:11:16 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:11:16 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:11:16 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:11:16 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:16 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10,"duration":189,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"情字已瘦","artist":"昊轩京剧-吴昊","album":"情字已瘦","uri":"INTERNAL/昊轩京剧-吴昊 - 情字已瘦.flac","trackType":"flac"} Jun 29 15:11:16 volumio volumio[2559]: verbose: CURRENT POSITION 0 Jun 29 15:11:16 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:11:16 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:11:16 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:11:16 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:16 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:16 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:11:16 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:16 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:16 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:16 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:16 volumio volumio[2559]: info: ------------------------------ 155ms Jun 29 15:11:16 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 78 milliseconds Jun 29 15:11:16 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 76 milliseconds Jun 29 15:11:16 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:11:16 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:11:16 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:11:16 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:11:16 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:16 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":189,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1106 Kbps","isStreaming":false,"title":"情字已瘦","artist":"昊轩京剧-吴昊","album":"情字已瘦","uri":"INTERNAL/昊轩京剧-吴昊 - 情字已瘦.flac","trackType":"flac"} Jun 29 15:11:16 volumio volumio[2559]: verbose: CURRENT POSITION 0 Jun 29 15:11:16 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:11:16 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:11:16 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:11:16 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:16 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:16 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:11:16 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:16 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:16 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:16 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:16 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:11:16 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:11:16 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:16 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":189,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1106 Kbps","isStreaming":false,"title":"情字已瘦","artist":"昊轩京剧-吴昊","album":"情字已瘦","uri":"INTERNAL/昊轩京剧-吴昊 - 情字已瘦.flac","trackType":"flac"} Jun 29 15:11:16 volumio volumio[2559]: verbose: CURRENT POSITION 0 Jun 29 15:11:16 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:11:16 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:11:16 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:11:16 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:16 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:16 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:16 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:16 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:16 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:16 volumio volumio[2559]: info: ------------------------------ 233ms Jun 29 15:11:16 volumio volumio[2559]: info: ------------------------------ 207ms Jun 29 15:11:18 volumio volumio[2559]: info: VolumeController::SetAlsaVolume32 Jun 29 15:11:18 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:18 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:18 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:11:18 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:19 volumio volumio[2559]: info: VolumeController::SetAlsaVolume28 Jun 29 15:11:19 volumio volumio[2559]: info: VolumeController::SetAlsaVolume27 Jun 29 15:11:19 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:19 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:19 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:11:19 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:19 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:19 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:19 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:19 volumio volumio[2559]: info: VolumeController::SetAlsaVolume26 Jun 29 15:11:19 volumio volumio[2559]: info: VolumeController::SetAlsaVolume24 Jun 29 15:11:19 volumio volumio[2559]: info: VolumeController::SetAlsaVolume23 Jun 29 15:11:19 volumio volumio[2559]: info: VolumeController::SetAlsaVolume22 Jun 29 15:11:19 volumio volumio[2559]: info: VolumeController::SetAlsaVolume20 Jun 29 15:11:19 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:19 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:19 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:11:19 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:19 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:19 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:19 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:19 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:19 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:19 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:19 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:19 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:19 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:19 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:19 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:19 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:19 volumio volumio[2559]: info: VolumeController::SetAlsaVolume18 Jun 29 15:11:19 volumio volumio[2559]: info: VolumeController::SetAlsaVolume16 Jun 29 15:11:19 volumio volumio[2559]: info: VolumeController::SetAlsaVolume15 Jun 29 15:11:20 volumio volumio[2559]: info: VolumeController::SetAlsaVolume14 Jun 29 15:11:20 volumio volumio[2559]: info: VolumeController::SetAlsaVolume13 Jun 29 15:11:20 volumio volumio[2559]: info: VolumeController::SetAlsaVolume12 Jun 29 15:11:20 volumio volumio[2559]: info: VolumeController::SetAlsaVolume11 Jun 29 15:11:20 volumio volumio[2559]: info: VolumeController::SetAlsaVolume10 Jun 29 15:11:20 volumio volumio[2559]: info: VolumeController::SetAlsaVolume9 Jun 29 15:11:20 volumio volumio[2559]: info: VolumeController::SetAlsaVolume8 Jun 29 15:11:20 volumio volumio[2559]: info: VolumeController::SetAlsaVolume6 Jun 29 15:11:20 volumio volumio[2559]: info: VolumeController::SetAlsaVolume4 Jun 29 15:11:20 volumio volumio[2559]: info: VolumeController::SetAlsaVolume2 Jun 29 15:11:20 volumio volumio[2559]: info: VolumeController::SetAlsaVolume0 Jun 29 15:11:20 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:20 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:11:20 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:20 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:20 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:20 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:20 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:20 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:20 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:20 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:20 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:20 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:20 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:20 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:20 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:20 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:20 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:20 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:20 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:20 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:20 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:20 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:20 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:20 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:20 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:20 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:20 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:20 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:20 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:26 volumio volumio[2559]: info: MyVolumio login type: Token Jun 29 15:11:30 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 29 15:11:30 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 29 15:11:30 volumio volumio[2559]: info: Discovery: Getting this device information Jun 29 15:11:30 volumio volumio[2559]: info: CoreCommandRouter::volumioGetState Jun 29 15:11:30 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:30 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 29 15:11:31 volumio kernel: hwmon hwmon1: Voltage normalised Jun 29 15:11:35 volumio kernel: hwmon hwmon1: Undervoltage detected! Jun 29 15:11:39 volumio kernel: hwmon hwmon1: Voltage normalised Jun 29 15:11:44 volumio volumio[2559]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jun 29 15:11:44 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jun 29 15:11:44 volumio volumio[2559]: info: CoreCommandRouter::volumioGetState Jun 29 15:11:44 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:44 volumio volumio[2559]: info: Restoring Previous Volume level: 0 false true Jun 29 15:11:44 volumio volumio[2559]: info: VolumeController::SetAlsaVolume100 Jun 29 15:11:44 volumio volumio[2559]: info: Enable softmixer device for audio device number 3 Jun 29 15:11:44 volumio volumio[2559]: info: CoreCommandRouter::volumioStop Jun 29 15:11:44 volumio volumio[2559]: info: CoreStateMachine::stop Jun 29 15:11:44 volumio volumio[2559]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 29 15:11:44 volumio volumio[2559]: info: CoreStateMachine::stPlaybackTimer Jun 29 15:11:44 volumio volumio[2559]: info: CoreStateMachine::updateTrackBlock Jun 29 15:11:44 volumio volumio[2559]: info: CorePlayQueue::getTrackBlock Jun 29 15:11:44 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:44 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:44 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:11:44 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:44 volumio volumio[2559]: info: CoreStateMachine::serviceStop Jun 29 15:11:44 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:44 volumio volumio[2559]: info: CoreCommandRouter::serviceStop Jun 29 15:11:44 volumio volumio[2559]: info: ControllerMpd::stop Jun 29 15:11:44 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand stop Jun 29 15:11:44 volumio volumio[2559]: info: Enable softmixer device for audio device undefined Jun 29 15:11:44 volumio volumio[2559]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 29 15:11:44 volumio volumio[2559]: info: Updating Volume Controller Parameters: Device: 3 Name: Cyberdrive Audio Driver Mixer: Cyberdrive Audio Driver Output Playback Swi Max Vol: 80 Vol Curve; logarithmic Vol Steps: 1 Jun 29 15:11:44 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 29 15:11:44 volumio volumio[2559]: info: Disabling external Volume Control Jun 29 15:11:44 volumio volumio[2559]: info: Output device has changed, restarting MPD Jun 29 15:11:44 volumio volumio[2559]: info: Output device has changed, restarting Shairport Sync Jun 29 15:11:44 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:44 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 29 15:11:44 volumio sudo[3207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 29 15:11:44 volumio sudo[3207]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 15:11:44 volumio sudo[3207]: pam_unix(sudo:session): session closed for user root Jun 29 15:11:44 volumio sudo[3209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 29 15:11:44 volumio sudo[3209]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 15:11:44 volumio systemd[1]: Stopping Music Player Daemon... Jun 29 15:11:44 volumio volumio[2559]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 29 15:11:44 volumio volumio[2559]: info: Volume configurations have been set Jun 29 15:11:44 volumio systemd[1]: mpd.service: Succeeded. Jun 29 15:11:44 volumio systemd[1]: Stopped Music Player Daemon. Jun 29 15:11:44 volumio volumio[2559]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 29 15:11:44 volumio volumio[2559]: info: Updating Volume Controller Parameters: Device: 3 Name: Cyberdrive Audio Driver Mixer: Cyberdrive Audio Driver Output Playback Swi Max Vol: 80 Vol Curve; logarithmic Vol Steps: 1 Jun 29 15:11:44 volumio systemd[1]: Starting Music Player Daemon... Jun 29 15:11:44 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 29 15:11:44 volumio volumio[2559]: info: Disabling external Volume Control Jun 29 15:11:44 volumio volumio[2559]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 29 15:11:44 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:44 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:44 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:11:44 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:44 volumio volumio[2559]: error: MPD error: Error: write EPIPE Jun 29 15:11:44 volumio volumio[2559]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jun 29 15:11:44 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:44 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:44 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:11:44 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:44 volumio volumio[2559]: info: MPD Permissions set Jun 29 15:11:44 volumio volumio[2559]: Unable to parse: Jun 29 15:11:44 volumio volumio[2559]: Simple mixer control 'Cyberdrive Audio Driver Output Playback Swi',0 Jun 29 15:11:44 volumio volumio[2559]: Capabilities: pswitch Jun 29 15:11:44 volumio volumio[2559]: Playback channels: Front Left - Front Right Jun 29 15:11:44 volumio volumio[2559]: Mono: Jun 29 15:11:44 volumio volumio[2559]: Front Left: Playback [on] Jun 29 15:11:44 volumio volumio[2559]: Front Right: Playback [on] Jun 29 15:11:44 volumio volumio[2559]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jun 29 15:11:44 volumio sudo[3216]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 29 15:11:44 volumio sudo[3216]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 15:11:44 volumio sudo[3216]: pam_unix(sudo:session): session closed for user root Jun 29 15:11:44 volumio volumio[2559]: info: Software Volume ALSA configuration written Jun 29 15:11:44 volumio volumio[2559]: info: Preparing to generate the ALSA configuration file Jun 29 15:11:44 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:44 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:44 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:44 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:44 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:44 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:44 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 29 15:11:44 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:45 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 29 15:11:45 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 29 15:11:45 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 29 15:11:45 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 29 15:11:45 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 29 15:11:45 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 29 15:11:45 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 29 15:11:45 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 29 15:11:45 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 29 15:11:45 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 29 15:11:45 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 29 15:11:45 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 29 15:11:45 volumio volumio[2559]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 29 15:11:45 volumio volumio[2559]: info: Reading ALSA contributions from plugins. Jun 29 15:11:45 volumio volumio[2559]: Unable to parse: Jun 29 15:11:45 volumio volumio[2559]: Simple mixer control 'Cyberdrive Audio Driver Output Playback Swi',0 Jun 29 15:11:45 volumio volumio[2559]: Capabilities: pswitch Jun 29 15:11:45 volumio volumio[2559]: Playback channels: Front Left - Front Right Jun 29 15:11:45 volumio volumio[2559]: Mono: Jun 29 15:11:45 volumio volumio[2559]: Front Left: Playback [on] Jun 29 15:11:45 volumio volumio[2559]: Front Right: Playback [on] Jun 29 15:11:45 volumio volumio[2559]: info: VolumeController:: Volume=undefined Mute =false Jun 29 15:11:45 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:45 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:45 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:11:45 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:45 volumio volumio[2559]: info: Starting Shairport Sync Jun 29 15:11:45 volumio volumio[2559]: info: Asound.conf file written Jun 29 15:11:45 volumio sudo[3251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 29 15:11:45 volumio sudo[3251]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 15:11:45 volumio sudo[3254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 29 15:11:45 volumio sudo[3254]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 15:11:45 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 29 15:11:45 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 29 15:11:45 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 29 15:11:45 volumio sudo[3254]: pam_unix(sudo:session): session closed for user root Jun 29 15:11:45 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 29 15:11:45 volumio sudo[3251]: pam_unix(sudo:session): session closed for user root Jun 29 15:11:45 volumio volumio[2559]: No state is present for card b1 Jun 29 15:11:45 volumio volumio[2559]: /usr/share/alsa/init/bcm2835:14: control element not found Jun 29 15:11:45 volumio volumio[2559]: No state is present for card b1 Jun 29 15:11:45 volumio volumio[2559]: No state is present for card Headphones Jun 29 15:11:45 volumio volumio[2559]: /usr/share/alsa/init/bcm2835:20: control element not found Jun 29 15:11:45 volumio volumio[2559]: No state is present for card Headphones Jun 29 15:11:45 volumio volumio[2559]: No state is present for card player Jun 29 15:11:45 volumio volumio[2559]: Found hardware: "USB-Audio" "USB Mixer" "USB1fc9:0001" "" "" Jun 29 15:11:45 volumio volumio[2559]: Hardware is initialized using a generic method Jun 29 15:11:45 volumio volumio[2559]: No state is present for card player Jun 29 15:11:45 volumio volumio[2559]: No state is present for card Driver Jun 29 15:11:45 volumio volumio[2559]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:305d" "" "" Jun 29 15:11:45 volumio volumio[2559]: Hardware is initialized using a generic method Jun 29 15:11:45 volumio volumio[2559]: No state is present for card Driver Jun 29 15:11:45 volumio volumio[2559]: No state is present for card UP4 Jun 29 15:11:45 volumio volumio[2559]: Found hardware: "USB-Audio" "USB Mixer" "USB0a12:1244" "" "" Jun 29 15:11:45 volumio volumio[2559]: Hardware is initialized using a generic method Jun 29 15:11:45 volumio volumio[2559]: No state is present for card UP4 Jun 29 15:11:45 volumio volumio[2559]: info: Output device has changed, restarting MPD Jun 29 15:11:45 volumio volumio[2559]: info: Output device has changed, restarting Shairport Sync Jun 29 15:11:45 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:45 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 29 15:11:45 volumio sudo[3262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 29 15:11:45 volumio sudo[3262]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 15:11:45 volumio sudo[3262]: pam_unix(sudo:session): session closed for user root Jun 29 15:11:45 volumio sudo[3264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 29 15:11:45 volumio sudo[3264]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 15:11:45 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 29 15:11:45 volumio systemd[1]: mpd.service: Succeeded. Jun 29 15:11:45 volumio volumio[2559]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 29 15:11:45 volumio systemd[1]: Stopped Music Player Daemon. Jun 29 15:11:45 volumio systemd[1]: Starting Music Player Daemon... Jun 29 15:11:46 volumio kernel: hwmon hwmon1: Undervoltage detected! Jun 29 15:11:46 volumio sudo[3270]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 29 15:11:46 volumio sudo[3270]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 15:11:46 volumio sudo[3270]: pam_unix(sudo:session): session closed for user root Jun 29 15:11:47 volumio volumio[2559]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jun 29 15:11:47 volumio volumio[2559]: No state is present for card b1 Jun 29 15:11:47 volumio volumio[2559]: /usr/share/alsa/init/bcm2835:14: control element not found Jun 29 15:11:47 volumio volumio[2559]: No state is present for card b1 Jun 29 15:11:47 volumio volumio[2559]: No state is present for card Headphones Jun 29 15:11:47 volumio volumio[2559]: /usr/share/alsa/init/bcm2835:20: control element not found Jun 29 15:11:47 volumio volumio[2559]: No state is present for card Headphones Jun 29 15:11:47 volumio volumio[2559]: No state is present for card player Jun 29 15:11:47 volumio volumio[2559]: Found hardware: "USB-Audio" "USB Mixer" "USB1fc9:0001" "" "" Jun 29 15:11:47 volumio volumio[2559]: Hardware is initialized using a generic method Jun 29 15:11:47 volumio volumio[2559]: No state is present for card player Jun 29 15:11:47 volumio volumio[2559]: No state is present for card Driver Jun 29 15:11:47 volumio volumio[2559]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:305d" "" "" Jun 29 15:11:47 volumio volumio[2559]: Hardware is initialized using a generic method Jun 29 15:11:47 volumio volumio[2559]: No state is present for card Driver Jun 29 15:11:47 volumio volumio[2559]: No state is present for card UP4 Jun 29 15:11:47 volumio volumio[2559]: Found hardware: "USB-Audio" "USB Mixer" "USB0a12:1244" "" "" Jun 29 15:11:47 volumio volumio[2559]: Hardware is initialized using a generic method Jun 29 15:11:47 volumio volumio[2559]: No state is present for card UP4 Jun 29 15:11:47 volumio volumio[2559]: info: Output device has changed, restarting MPD Jun 29 15:11:47 volumio volumio[2559]: info: Output device has changed, restarting Shairport Sync Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 29 15:11:47 volumio sudo[3279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 29 15:11:47 volumio sudo[3279]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 15:11:47 volumio sudo[3279]: pam_unix(sudo:session): session closed for user root Jun 29 15:11:47 volumio sudo[3281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 29 15:11:47 volumio sudo[3281]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 15:11:47 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 29 15:11:47 volumio systemd[1]: mpd.service: Succeeded. Jun 29 15:11:47 volumio systemd[1]: Stopped Music Player Daemon. Jun 29 15:11:47 volumio volumio[2559]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 29 15:11:47 volumio systemd[1]: Starting Music Player Daemon... Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 29 15:11:47 volumio volumio[2559]: info: Updating Volume Controller Parameters: Device: 3 Name: softvolume Mixer: SoftMaster Max Vol: 80 Vol Curve; logarithmic Vol Steps: 1 Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 29 15:11:47 volumio volumio[2559]: info: Disabling external Volume Control Jun 29 15:11:47 volumio volumio[2559]: info: MPD Permissions set Jun 29 15:11:47 volumio volumio[2559]: info: MPD Permissions set Jun 29 15:11:47 volumio volumio[2559]: info: Shairport-Sync Started Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:47 volumio sudo[3287]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 29 15:11:47 volumio sudo[3287]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 15:11:47 volumio sudo[3287]: pam_unix(sudo:session): session closed for user root Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 15:11:47 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:47 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:11:47 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:47 volumio volumio[2559]: info: Starting Shairport Sync Jun 29 15:11:47 volumio volumio[2559]: info: Starting Shairport Sync Jun 29 15:11:47 volumio sudo[3309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 29 15:11:47 volumio sudo[3309]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 15:11:47 volumio sudo[3311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 29 15:11:47 volumio sudo[3311]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 15:11:47 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 29 15:11:47 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 29 15:11:47 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 29 15:11:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 29 15:11:48 volumio sudo[3309]: pam_unix(sudo:session): session closed for user root Jun 29 15:11:48 volumio volumio[2559]: info: Shairport-Sync Started Jun 29 15:11:48 volumio sudo[3311]: pam_unix(sudo:session): session closed for user root Jun 29 15:11:48 volumio volumio[2559]: info: Shairport-Sync Started Jun 29 15:11:48 volumio volumio[2559]: info: VolumeController::SetAlsaVolume0 Jun 29 15:11:48 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:48 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:48 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:11:48 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:49 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jun 29 15:11:49 volumio sudo[3321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 29 15:11:49 volumio sudo[3321]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 15:11:49 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 29 15:11:49 volumio systemd[1]: mpd.service: Succeeded. Jun 29 15:11:49 volumio systemd[1]: Stopped Music Player Daemon. Jun 29 15:11:49 volumio systemd[1]: Starting Music Player Daemon... Jun 29 15:11:49 volumio sudo[3323]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 29 15:11:49 volumio sudo[3323]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 15:11:49 volumio sudo[3323]: pam_unix(sudo:session): session closed for user root Jun 29 15:11:51 volumio mpd[3325]: Jun 29 15:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 29 15:11:51 volumio systemd[1]: Started Music Player Daemon. Jun 29 15:11:51 volumio sudo[3209]: pam_unix(sudo:session): session closed for user root Jun 29 15:11:51 volumio sudo[3281]: pam_unix(sudo:session): session closed for user root Jun 29 15:11:51 volumio sudo[3321]: pam_unix(sudo:session): session closed for user root Jun 29 15:11:51 volumio sudo[3264]: pam_unix(sudo:session): session closed for user root Jun 29 15:11:51 volumio volumio[2559]: error: MPD error: The expression evaluated to a falsy value: Jun 29 15:11:51 volumio volumio[2559]: assert.ok(self.idling) Jun 29 15:11:51 volumio volumio[2559]: error: The expression evaluated to a falsy value: Jun 29 15:11:51 volumio volumio[2559]: assert.ok(self.idling) Jun 29 15:11:51 volumio volumio[2559]: error: MPD error: The expression evaluated to a falsy value: Jun 29 15:11:51 volumio volumio[2559]: assert.ok(self.idling) Jun 29 15:11:51 volumio volumio[2559]: error: The expression evaluated to a falsy value: Jun 29 15:11:51 volumio volumio[2559]: assert.ok(self.idling) Jun 29 15:11:51 volumio volumio[2559]: error: MPD error: The expression evaluated to a falsy value: Jun 29 15:11:51 volumio volumio[2559]: assert.ok(self.idling) Jun 29 15:11:51 volumio volumio[2559]: error: The expression evaluated to a falsy value: Jun 29 15:11:51 volumio volumio[2559]: assert.ok(self.idling) Jun 29 15:11:51 volumio volumio[2559]: error: updateQueue error: null Jun 29 15:11:51 volumio volumio[2559]: info: CoreCommandRouter::volumioPlay Jun 29 15:11:51 volumio volumio[2559]: info: CoreStateMachine::play index undefined Jun 29 15:11:51 volumio volumio[2559]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 29 15:11:51 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:51 volumio volumio[2559]: info: CoreStateMachine::startPlaybackTimer Jun 29 15:11:51 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/昊轩京剧-吴昊 - 情字已瘦.flac Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand stop Jun 29 15:11:51 volumio volumio[2559]: info: sendMpdCommand stop took 1 milliseconds Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand clear Jun 29 15:11:51 volumio volumio[2559]: info: Jun 29 15:11:51 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:11:51 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:11:51 volumio volumio[2559]: info: sendMpdCommand clear took 1 milliseconds Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/昊轩京剧-吴昊 - 情字已瘦.flac" Jun 29 15:11:51 volumio volumio[2559]: info: Jun 29 15:11:51 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:11:51 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:11:51 volumio volumio[2559]: info: Jun 29 15:11:51 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:11:51 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:11:51 volumio volumio[2559]: info: Jun 29 15:11:51 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:11:51 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:11:51 volumio volumio[2559]: error: updateQueue error: null Jun 29 15:11:51 volumio volumio[2559]: info: Jun 29 15:11:51 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:11:51 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:11:51 volumio volumio[2559]: info: ------------------------------ 9ms Jun 29 15:11:51 volumio volumio[2559]: info: sendMpdCommand add "INTERNAL/昊轩京剧-吴昊 - 情字已瘦.flac" took 7 milliseconds Jun 29 15:11:51 volumio volumio[2559]: info: ------------------------------ 6ms Jun 29 15:11:51 volumio volumio[2559]: info: ------------------------------ 5ms Jun 29 15:11:51 volumio volumio[2559]: info: ------------------------------ 5ms Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand play Jun 29 15:11:51 volumio volumio[2559]: info: Jun 29 15:11:51 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:11:51 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:11:51 volumio volumio[2559]: info: Jun 29 15:11:51 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:11:51 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:11:51 volumio volumio[2559]: info: Jun 29 15:11:51 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:11:51 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:11:51 volumio volumio[2559]: info: Jun 29 15:11:51 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:11:51 volumio volumio[2559]: info: ------------------------------ 12ms Jun 29 15:11:51 volumio volumio[2559]: info: sendMpdCommand play took 9 milliseconds Jun 29 15:11:51 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:11:51 volumio volumio[2559]: info: ------------------------------ 9ms Jun 29 15:11:51 volumio volumio[2559]: info: ------------------------------ 8ms Jun 29 15:11:51 volumio volumio[2559]: info: ------------------------------ 6ms Jun 29 15:11:51 volumio volumio[2559]: info: Jun 29 15:11:51 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:11:51 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:11:51 volumio volumio[2559]: info: Jun 29 15:11:51 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:11:51 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:11:51 volumio volumio[2559]: info: Jun 29 15:11:51 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:11:51 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:11:51 volumio volumio[2559]: info: sendMpdCommand status took 39 milliseconds Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:11:51 volumio volumio[2559]: info: Jun 29 15:11:51 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:11:51 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:11:51 volumio volumio[2559]: info: sendMpdCommand status took 40 milliseconds Jun 29 15:11:51 volumio volumio[2559]: info: sendMpdCommand status took 39 milliseconds Jun 29 15:11:51 volumio volumio[2559]: info: sendMpdCommand status took 38 milliseconds Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:11:51 volumio volumio[2559]: info: Jun 29 15:11:51 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:11:51 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:11:51 volumio volumio[2559]: info: Jun 29 15:11:51 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:11:51 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:11:51 volumio volumio[2559]: info: Jun 29 15:11:51 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:11:51 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:11:51 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 16 milliseconds Jun 29 15:11:51 volumio volumio[2559]: info: sendMpdCommand status took 14 milliseconds Jun 29 15:11:51 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 29 15:11:51 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 29 15:11:51 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 29 15:11:51 volumio volumio[2559]: info: sendMpdCommand status took 7 milliseconds Jun 29 15:11:51 volumio volumio[2559]: info: sendMpdCommand status took 6 milliseconds Jun 29 15:11:51 volumio volumio[2559]: info: sendMpdCommand status took 4 milliseconds Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:11:51 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:11:51 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:11:51 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:11:51 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:51 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":189,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"情字已瘦","artist":"昊轩京剧-吴昊","album":"情字已瘦","uri":"INTERNAL/昊轩京剧-吴昊 - 情字已瘦.flac","trackType":"flac"} Jun 29 15:11:51 volumio volumio[2559]: verbose: CURRENT POSITION 0 Jun 29 15:11:51 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:11:51 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus stop Jun 29 15:11:51 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:11:51 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:11:51 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:51 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10,"duration":189,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"情字已瘦","artist":"昊轩京剧-吴昊","album":"情字已瘦","uri":"INTERNAL/昊轩京剧-吴昊 - 情字已瘦.flac","trackType":"flac"} Jun 29 15:11:51 volumio volumio[2559]: verbose: CURRENT POSITION 0 Jun 29 15:11:51 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:11:51 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:11:51 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:11:51 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:51 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:51 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:11:51 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:51 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:51 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:51 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:51 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:11:51 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:11:51 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:51 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10,"duration":189,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"937 Kbps","isStreaming":false,"title":"情字已瘦","artist":"昊轩京剧-吴昊","album":"情字已瘦","uri":"INTERNAL/昊轩京剧-吴昊 - 情字已瘦.flac","trackType":"flac"} Jun 29 15:11:51 volumio volumio[2559]: verbose: CURRENT POSITION 0 Jun 29 15:11:51 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:11:51 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:11:51 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:11:51 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:51 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:51 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:51 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:51 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:51 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:51 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:11:51 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:11:51 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:51 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":73,"duration":189,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"937 Kbps","isStreaming":false,"title":"情字已瘦","artist":"昊轩京剧-吴昊","album":"情字已瘦","uri":"INTERNAL/昊轩京剧-吴昊 - 情字已瘦.flac","trackType":"flac"} Jun 29 15:11:51 volumio volumio[2559]: verbose: CURRENT POSITION 0 Jun 29 15:11:51 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:11:51 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:11:51 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:11:51 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:51 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:51 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:11:51 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:51 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:51 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:51 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:51 volumio volumio[2559]: info: ------------------------------ 99ms Jun 29 15:11:52 volumio volumio[2559]: info: ------------------------------ 196ms Jun 29 15:11:52 volumio volumio[2559]: info: ------------------------------ 194ms Jun 29 15:11:52 volumio volumio[2559]: info: ------------------------------ 194ms Jun 29 15:11:52 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 150 milliseconds Jun 29 15:11:52 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 150 milliseconds Jun 29 15:11:52 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 149 milliseconds Jun 29 15:11:52 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 150 milliseconds Jun 29 15:11:52 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:11:52 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:11:52 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:11:52 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:11:52 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:11:52 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:11:52 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:52 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":189,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1106 Kbps","isStreaming":false,"title":"情字已瘦","artist":"昊轩京剧-吴昊","album":"情字已瘦","uri":"INTERNAL/昊轩京剧-吴昊 - 情字已瘦.flac","trackType":"flac"} Jun 29 15:11:52 volumio volumio[2559]: verbose: CURRENT POSITION 0 Jun 29 15:11:52 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:11:52 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:11:52 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:11:52 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:52 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:52 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:11:52 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:52 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:52 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:52 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:52 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:11:52 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:11:52 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:52 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":189,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1106 Kbps","isStreaming":false,"title":"情字已瘦","artist":"昊轩京剧-吴昊","album":"情字已瘦","uri":"INTERNAL/昊轩京剧-吴昊 - 情字已瘦.flac","trackType":"flac"} Jun 29 15:11:52 volumio volumio[2559]: verbose: CURRENT POSITION 0 Jun 29 15:11:52 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:11:52 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:11:52 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:11:52 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:52 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:52 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:52 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:52 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:52 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:52 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:11:52 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:11:52 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:52 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":189,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1106 Kbps","isStreaming":false,"title":"情字已瘦","artist":"昊轩京剧-吴昊","album":"情字已瘦","uri":"INTERNAL/昊轩京剧-吴昊 - 情字已瘦.flac","trackType":"flac"} Jun 29 15:11:52 volumio volumio[2559]: verbose: CURRENT POSITION 0 Jun 29 15:11:52 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:11:52 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:11:52 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:11:52 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:52 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:52 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:52 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:52 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:52 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:52 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:11:52 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:11:52 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:52 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":189,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1106 Kbps","isStreaming":false,"title":"情字已瘦","artist":"昊轩京剧-吴昊","album":"情字已瘦","uri":"INTERNAL/昊轩京剧-吴昊 - 情字已瘦.flac","trackType":"flac"} Jun 29 15:11:52 volumio volumio[2559]: verbose: CURRENT POSITION 0 Jun 29 15:11:52 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:11:52 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:11:52 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:11:52 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:52 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:52 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:52 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:52 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:52 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:52 volumio volumio[2559]: info: ------------------------------ 357ms Jun 29 15:11:52 volumio volumio[2559]: info: ------------------------------ 351ms Jun 29 15:11:52 volumio volumio[2559]: info: ------------------------------ 350ms Jun 29 15:11:52 volumio volumio[2559]: info: ------------------------------ 350ms Jun 29 15:11:56 volumio volumio[2559]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 29 15:11:56 volumio volumio[2559]: info: Retrying Login Due to Network Error Jun 29 15:11:56 volumio ntpd[724]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 29 15:11:58 volumio volumio[2559]: info: CoreCommandRouter::volumioSeek Jun 29 15:11:58 volumio volumio[2559]: info: CoreStateMachine::seek Jun 29 15:11:58 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:58 volumio volumio[2559]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/昊轩京剧-吴昊 - 情字已瘦.flac","service":"mpd","name":"昊轩京剧-吴昊 - 情字已瘦.flac","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=913&web=//extralarge&path=%2FINTERNAL&metadata=false","duration":189,"trackType":"flac","samplerate":"48 kHz","bitdepth":"24 bit","channels":2} Jun 29 15:11:58 volumio volumio[2559]: info: CoreStateMachine::startPlaybackTimer Jun 29 15:11:58 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:58 volumio volumio[2559]: info: ControllerMpd::seek Jun 29 15:11:58 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:58 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:58 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:11:58 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:58 volumio volumio[2559]: error: null Jun 29 15:11:58 volumio volumio[2559]: info: Jun 29 15:11:58 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:11:58 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:11:58 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:11:58 volumio volumio[2559]: info: Jun 29 15:11:58 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:11:58 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:11:58 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:11:58 volumio volumio[2559]: info: Jun 29 15:11:58 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:11:58 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:11:58 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:11:58 volumio volumio[2559]: info: Jun 29 15:11:58 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:11:58 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:11:58 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:11:58 volumio volumio[2559]: info: sendMpdCommand status took 8 milliseconds Jun 29 15:11:58 volumio volumio[2559]: info: sendMpdCommand status took 6 milliseconds Jun 29 15:11:58 volumio volumio[2559]: info: sendMpdCommand status took 4 milliseconds Jun 29 15:11:58 volumio volumio[2559]: info: sendMpdCommand status took 4 milliseconds Jun 29 15:11:58 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:11:58 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:11:58 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:11:58 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:11:58 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:11:58 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:11:58 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:11:58 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:11:58 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 29 15:11:58 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:11:58 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:11:58 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:11:58 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:11:58 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:58 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":65000,"duration":189,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1580 Kbps","isStreaming":false,"title":"情字已瘦","artist":"昊轩京剧-吴昊","album":"情字已瘦","uri":"INTERNAL/昊轩京剧-吴昊 - 情字已瘦.flac","trackType":"flac"} Jun 29 15:11:58 volumio volumio[2559]: verbose: CURRENT POSITION 0 Jun 29 15:11:58 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:11:58 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:11:58 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:11:58 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:58 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:58 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:58 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:58 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:58 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:58 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:11:58 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:11:58 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:58 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":65000,"duration":189,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1580 Kbps","isStreaming":false,"title":"情字已瘦","artist":"昊轩京剧-吴昊","album":"情字已瘦","uri":"INTERNAL/昊轩京剧-吴昊 - 情字已瘦.flac","trackType":"flac"} Jun 29 15:11:58 volumio volumio[2559]: verbose: CURRENT POSITION 0 Jun 29 15:11:58 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:11:58 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:11:58 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:11:58 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:58 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:58 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:58 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:58 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:58 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:58 volumio volumio[2559]: info: ------------------------------ 77ms Jun 29 15:11:58 volumio volumio[2559]: info: ------------------------------ 76ms Jun 29 15:11:58 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 66 milliseconds Jun 29 15:11:58 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 67 milliseconds Jun 29 15:11:58 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:11:58 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:11:58 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:11:58 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:11:58 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:58 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":65000,"duration":189,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1580 Kbps","isStreaming":false,"title":"情字已瘦","artist":"昊轩京剧-吴昊","album":"情字已瘦","uri":"INTERNAL/昊轩京剧-吴昊 - 情字已瘦.flac","trackType":"flac"} Jun 29 15:11:58 volumio volumio[2559]: verbose: CURRENT POSITION 0 Jun 29 15:11:58 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:11:58 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:11:58 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:11:58 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:58 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:58 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:58 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:58 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:58 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:58 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:11:58 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:11:58 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:58 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":65000,"duration":189,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1580 Kbps","isStreaming":false,"title":"情字已瘦","artist":"昊轩京剧-吴昊","album":"情字已瘦","uri":"INTERNAL/昊轩京剧-吴昊 - 情字已瘦.flac","trackType":"flac"} Jun 29 15:11:58 volumio volumio[2559]: verbose: CURRENT POSITION 0 Jun 29 15:11:58 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:11:58 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:11:58 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:11:58 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:58 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:58 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:58 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:11:58 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:11:58 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:11:58 volumio volumio[2559]: info: ------------------------------ 140ms Jun 29 15:11:58 volumio volumio[2559]: info: ------------------------------ 140ms Jun 29 15:12:00 volumio volumio[2559]: info: VolumeController::SetAlsaVolume1 Jun 29 15:12:00 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:00 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:00 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:00 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:00 volumio volumio[2559]: info: VolumeController::SetAlsaVolume2 Jun 29 15:12:00 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:00 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:00 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:00 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:00 volumio volumio[2559]: info: VolumeController::SetAlsaVolume3 Jun 29 15:12:00 volumio volumio[2559]: info: VolumeController::SetAlsaVolume8 Jun 29 15:12:00 volumio volumio[2559]: info: VolumeController::SetAlsaVolume12 Jun 29 15:12:00 volumio volumio[2559]: info: VolumeController::SetAlsaVolume16 Jun 29 15:12:00 volumio volumio[2559]: info: VolumeController::SetAlsaVolume19 Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume22 Jun 29 15:12:01 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:01 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:01 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:01 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:01 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:01 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:01 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:01 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:01 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:01 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:01 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:01 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:01 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:01 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:01 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:01 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:01 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:01 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:01 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume24 Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume26 Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume28 Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume30 Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume31 Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume32 Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume33 Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume35 Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume37 Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume39 Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume40 Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume41 Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume42 Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume43 Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume44 Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume45 Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume46 Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume47 Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume48 Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume49 Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume50 Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume51 Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume52 Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume53 Jun 29 15:12:01 volumio volumio[2559]: info: VolumeController::SetAlsaVolume54 Jun 29 15:12:02 volumio volumio[2559]: info: VolumeController::SetAlsaVolume55 Jun 29 15:12:02 volumio volumio[2559]: info: VolumeController::SetAlsaVolume56 Jun 29 15:12:02 volumio volumio[2559]: info: VolumeController::SetAlsaVolume57 Jun 29 15:12:02 volumio volumio[2559]: info: VolumeController::SetAlsaVolume58 Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:02 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:02 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:02 volumio volumio[2559]: info: VolumeController::SetAlsaVolume59 Jun 29 15:12:02 volumio volumio[2559]: info: VolumeController::SetAlsaVolume60 Jun 29 15:12:02 volumio volumio[2559]: info: VolumeController::SetAlsaVolume61 Jun 29 15:12:02 volumio volumio[2559]: info: VolumeController::SetAlsaVolume62 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume63 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume64 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume65 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume66 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume67 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume68 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume69 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume70 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume71 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume72 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume73 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume74 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume75 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume76 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume77 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume78 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume79 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume80 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume81 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume82 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume83 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume84 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume85 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume86 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume87 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume86 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume85 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume84 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume83 Jun 29 15:12:03 volumio volumio[2559]: info: VolumeController::SetAlsaVolume82 Jun 29 15:12:04 volumio volumio[2559]: info: VolumeController::SetAlsaVolume81 Jun 29 15:12:04 volumio volumio[2559]: info: VolumeController::SetAlsaVolume80 Jun 29 15:12:04 volumio volumio[2559]: info: VolumeController::SetAlsaVolume79 Jun 29 15:12:04 volumio volumio[2559]: info: VolumeController::SetAlsaVolume78 Jun 29 15:12:04 volumio volumio[2559]: info: VolumeController::SetAlsaVolume77 Jun 29 15:12:04 volumio volumio[2559]: info: VolumeController::SetAlsaVolume76 Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:04 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:04 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:04 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume75 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume74 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume73 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume72 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume71 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume70 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume69 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume68 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume67 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume66 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume65 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume64 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume63 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume62 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume61 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume60 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume59 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume58 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume57 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume56 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume55 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume54 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume53 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume52 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume51 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume50 Jun 29 15:12:05 volumio volumio[2559]: info: VolumeController::SetAlsaVolume49 Jun 29 15:12:05 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:05 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:05 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:05 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:05 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:05 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:05 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:05 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:05 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:05 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:05 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:05 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:05 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:05 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:05 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:05 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:05 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:05 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:05 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:05 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:05 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:05 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:05 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:05 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:05 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:05 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:05 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:05 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:05 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:05 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:05 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:05 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:05 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:05 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:05 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:05 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:05 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:05 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:05 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:05 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:05 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:05 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:05 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:05 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:05 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:05 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:05 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:05 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:05 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:06 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:06 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:06 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:06 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:06 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:06 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:06 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:06 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:06 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:06 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:06 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:06 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:06 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:06 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:06 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:06 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:06 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:06 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:06 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:06 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:06 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:06 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:06 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:06 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:06 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:06 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:06 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:06 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:06 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:06 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:06 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:06 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:06 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 29 15:12:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 29 15:12:06 volumio volumio[2559]: info: Discovery: Getting this device information Jun 29 15:12:06 volumio volumio[2559]: info: CoreCommandRouter::volumioGetState Jun 29 15:12:06 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 29 15:12:06 volumio volumio[2559]: info: MyVolumio login type: Token Jun 29 15:12:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 29 15:12:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 29 15:12:06 volumio volumio[2559]: info: Discovery: Getting this device information Jun 29 15:12:06 volumio volumio[2559]: info: CoreCommandRouter::volumioGetState Jun 29 15:12:06 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:06 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 29 15:12:08 volumio volumio[2559]: info: CoreCommandRouter::volumioNext Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::next Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::stop Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::stPlaybackTimer Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::updateTrackBlock Jun 29 15:12:08 volumio volumio[2559]: info: CorePlayQueue::getTrackBlock Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:08 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:08 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:08 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::serviceStop Jun 29 15:12:08 volumio volumio[2559]: info: CorePlayQueue::getTrack 0 Jun 29 15:12:08 volumio volumio[2559]: info: CoreCommandRouter::serviceStop Jun 29 15:12:08 volumio volumio[2559]: info: ControllerMpd::stop Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand stop Jun 29 15:12:08 volumio volumio[2559]: info: Jun 29 15:12:08 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:08 volumio volumio[2559]: info: sendMpdCommand stop took 10 milliseconds Jun 29 15:12:08 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::play index undefined Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 29 15:12:08 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::startPlaybackTimer Jun 29 15:12:08 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/阿朵 - 生如夏花 (Live).flac Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand stop Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::updateTrackBlock Jun 29 15:12:08 volumio volumio[2559]: info: CorePlayQueue::getTrackBlock Jun 29 15:12:08 volumio volumio[2559]: info: sendMpdCommand status took 5 milliseconds Jun 29 15:12:08 volumio volumio[2559]: info: sendMpdCommand stop took 2 milliseconds Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand clear Jun 29 15:12:08 volumio volumio[2559]: info: Jun 29 15:12:08 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:08 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:08 volumio volumio[2559]: info: Jun 29 15:12:08 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:08 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:08 volumio volumio[2559]: info: Jun 29 15:12:08 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:08 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:08 volumio volumio[2559]: info: Jun 29 15:12:08 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:12:08 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:12:08 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 29 15:12:08 volumio volumio[2559]: info: sendMpdCommand clear took 8 milliseconds Jun 29 15:12:08 volumio volumio[2559]: info: sendMpdCommand status took 7 milliseconds Jun 29 15:12:08 volumio volumio[2559]: info: sendMpdCommand status took 5 milliseconds Jun 29 15:12:08 volumio volumio[2559]: info: sendMpdCommand status took 4 milliseconds Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/阿朵 - 生如夏花 (Live).flac" Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:08 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:08 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:08 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:08 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:08 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:08 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:08 volumio volumio[2559]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"情字已瘦","artist":"昊轩京剧-吴昊","album":"情字已瘦","uri":"INTERNAL/昊轩京剧-吴昊 - 情字已瘦.flac","trackType":"flac"} Jun 29 15:12:08 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::syncState stateService stop Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus stop Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:08 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:08 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:08 volumio volumio[2559]: info: No code Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:08 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:08 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:08 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:08 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:08 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:08 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:08 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:08 volumio volumio[2559]: 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} Jun 29 15:12:08 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::syncState stateService stop Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus stop Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:08 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:08 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:08 volumio volumio[2559]: info: No code Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:08 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:08 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:08 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:08 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:08 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:08 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:08 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:08 volumio volumio[2559]: 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} Jun 29 15:12:08 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::syncState stateService stop Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus stop Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:08 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:08 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:08 volumio volumio[2559]: info: No code Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:08 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:08 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:08 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:08 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:08 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:08 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:08 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:08 volumio volumio[2559]: 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} Jun 29 15:12:08 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::syncState stateService stop Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus stop Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:08 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:08 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:08 volumio volumio[2559]: info: No code Jun 29 15:12:08 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:08 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:08 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:08 volumio volumio[2559]: info: ------------------------------ 134ms Jun 29 15:12:08 volumio volumio[2559]: info: ------------------------------ 122ms Jun 29 15:12:08 volumio volumio[2559]: info: ------------------------------ 121ms Jun 29 15:12:08 volumio volumio[2559]: info: ------------------------------ 120ms Jun 29 15:12:08 volumio volumio[2559]: info: Jun 29 15:12:08 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:12:08 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:12:08 volumio volumio[2559]: info: Jun 29 15:12:08 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:12:08 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:12:08 volumio volumio[2559]: info: Jun 29 15:12:08 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:12:08 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:12:08 volumio volumio[2559]: error: updateQueue error: null Jun 29 15:12:08 volumio volumio[2559]: info: Jun 29 15:12:08 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:12:08 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:12:08 volumio volumio[2559]: info: ------------------------------ 127ms Jun 29 15:12:08 volumio volumio[2559]: info: sendMpdCommand add "INTERNAL/阿朵 - 生如夏花 (Live).flac" took 123 milliseconds Jun 29 15:12:08 volumio volumio[2559]: info: ------------------------------ 9ms Jun 29 15:12:08 volumio volumio[2559]: info: ------------------------------ 7ms Jun 29 15:12:08 volumio volumio[2559]: info: ------------------------------ 6ms Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand play Jun 29 15:12:08 volumio volumio[2559]: info: Jun 29 15:12:08 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:12:08 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:12:08 volumio volumio[2559]: info: Jun 29 15:12:08 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:12:08 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:12:08 volumio volumio[2559]: info: Jun 29 15:12:08 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:12:08 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:12:08 volumio volumio[2559]: info: Jun 29 15:12:08 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:08 volumio volumio[2559]: info: ------------------------------ 31ms Jun 29 15:12:08 volumio volumio[2559]: info: sendMpdCommand play took 28 milliseconds Jun 29 15:12:08 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:08 volumio volumio[2559]: info: ------------------------------ 29ms Jun 29 15:12:08 volumio volumio[2559]: info: ------------------------------ 27ms Jun 29 15:12:08 volumio volumio[2559]: info: ------------------------------ 26ms Jun 29 15:12:08 volumio volumio[2559]: info: Jun 29 15:12:08 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:08 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:08 volumio volumio[2559]: info: Jun 29 15:12:08 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:08 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:08 volumio volumio[2559]: info: Jun 29 15:12:08 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:08 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:08 volumio volumio[2559]: info: Jun 29 15:12:08 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:08 volumio volumio[2559]: info: sendMpdCommand status took 18 milliseconds Jun 29 15:12:08 volumio volumio[2559]: info: sendMpdCommand status took 17 milliseconds Jun 29 15:12:08 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:08 volumio volumio[2559]: info: sendMpdCommand status took 16 milliseconds Jun 29 15:12:08 volumio volumio[2559]: info: sendMpdCommand status took 15 milliseconds Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:08 volumio volumio[2559]: info: Jun 29 15:12:08 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:08 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:08 volumio volumio[2559]: info: Jun 29 15:12:08 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:08 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:08 volumio volumio[2559]: info: Jun 29 15:12:08 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:08 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:08 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:09 volumio volumio[2559]: info: sendMpdCommand status took 53 milliseconds Jun 29 15:12:09 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 51 milliseconds Jun 29 15:12:09 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 50 milliseconds Jun 29 15:12:09 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 49 milliseconds Jun 29 15:12:09 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 49 milliseconds Jun 29 15:12:09 volumio volumio[2559]: info: sendMpdCommand status took 45 milliseconds Jun 29 15:12:09 volumio volumio[2559]: info: sendMpdCommand status took 43 milliseconds Jun 29 15:12:09 volumio volumio[2559]: info: sendMpdCommand status took 40 milliseconds Jun 29 15:12:09 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:09 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:09 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:09 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:09 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:09 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:09 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:09 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:09 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:09 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:09 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:09 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:09 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:09 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:09 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿朵 - 生如夏花 (Live).flac","trackType":"flac"} Jun 29 15:12:09 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus stop Jun 29 15:12:09 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:09 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:09 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"909 Kbps","isStreaming":false,"title":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿朵 - 生如夏花 (Live).flac","trackType":"flac"} Jun 29 15:12:09 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:12:09 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:09 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:09 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:09 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:09 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:09 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":52,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1145 Kbps","isStreaming":false,"title":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿朵 - 生如夏花 (Live).flac","trackType":"flac"} Jun 29 15:12:09 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:12:09 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:09 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:09 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:09 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:09 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:09 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":83,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1145 Kbps","isStreaming":false,"title":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿朵 - 生如夏花 (Live).flac","trackType":"flac"} Jun 29 15:12:09 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:12:09 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:09 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:09 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:09 volumio volumio[2559]: info: ------------------------------ 142ms Jun 29 15:12:09 volumio volumio[2559]: info: ------------------------------ 218ms Jun 29 15:12:09 volumio volumio[2559]: info: ------------------------------ 218ms Jun 29 15:12:09 volumio volumio[2559]: info: ------------------------------ 217ms Jun 29 15:12:09 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 147 milliseconds Jun 29 15:12:09 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 145 milliseconds Jun 29 15:12:09 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 145 milliseconds Jun 29 15:12:09 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 144 milliseconds Jun 29 15:12:09 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:09 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:09 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:09 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:09 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:09 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:09 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1204 Kbps","isStreaming":false,"title":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿朵 - 生如夏花 (Live).flac","trackType":"flac"} Jun 29 15:12:09 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:12:09 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:09 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:09 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:09 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:09 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:09 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1271 Kbps","isStreaming":false,"title":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿朵 - 生如夏花 (Live).flac","trackType":"flac"} Jun 29 15:12:09 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:12:09 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:09 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:09 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:09 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:09 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:09 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1271 Kbps","isStreaming":false,"title":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿朵 - 生如夏花 (Live).flac","trackType":"flac"} Jun 29 15:12:09 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:12:09 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:09 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:09 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:09 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:09 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:09 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1271 Kbps","isStreaming":false,"title":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿朵 - 生如夏花 (Live).flac","trackType":"flac"} Jun 29 15:12:09 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:12:09 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:09 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:09 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:09 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:09 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:09 volumio volumio[2559]: info: ------------------------------ 418ms Jun 29 15:12:09 volumio volumio[2559]: info: ------------------------------ 407ms Jun 29 15:12:09 volumio volumio[2559]: info: ------------------------------ 404ms Jun 29 15:12:09 volumio volumio[2559]: info: ------------------------------ 403ms Jun 29 15:12:12 volumio volumio[2559]: info: CoreCommandRouter::volumioSeek Jun 29 15:12:12 volumio volumio[2559]: info: CoreStateMachine::seek Jun 29 15:12:12 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:12 volumio volumio[2559]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/阿朵 - 生如夏花 (Live).flac","service":"mpd","name":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=913&web=%E9%98%BF%E6%9C%B5/%E4%B9%98%E9%A3%8E%E7%A0%B4%E6%B5%AA%E7%9A%84%E5%A7%90%E5%A7%902/extralarge&path=%2FINTERNAL&metadata=false","duration":202,"trackType":"flac","samplerate":"48 kHz","bitdepth":"24 bit","channels":2} Jun 29 15:12:12 volumio volumio[2559]: info: CoreStateMachine::startPlaybackTimer Jun 29 15:12:12 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:12 volumio volumio[2559]: info: ControllerMpd::seek Jun 29 15:12:12 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:12 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:12 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:12 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:12 volumio volumio[2559]: error: null Jun 29 15:12:12 volumio volumio[2559]: info: Jun 29 15:12:12 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:12 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:12 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:12 volumio volumio[2559]: info: Jun 29 15:12:12 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:12 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:12 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:12 volumio volumio[2559]: info: Jun 29 15:12:12 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:12 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:12 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:12 volumio volumio[2559]: info: Jun 29 15:12:12 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:12 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:12 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:12 volumio volumio[2559]: info: sendMpdCommand status took 10 milliseconds Jun 29 15:12:12 volumio volumio[2559]: info: sendMpdCommand status took 9 milliseconds Jun 29 15:12:12 volumio volumio[2559]: info: sendMpdCommand status took 7 milliseconds Jun 29 15:12:12 volumio volumio[2559]: info: sendMpdCommand status took 5 milliseconds Jun 29 15:12:12 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:12 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:12 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:12 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:12 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:12 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:12 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:12 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:12 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 29 15:12:12 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 29 15:12:12 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 29 15:12:12 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 29 15:12:12 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:12 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:12 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:12 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:12 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:12 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:12 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:12 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":62483,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1429 Kbps","isStreaming":false,"title":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿朵 - 生如夏花 (Live).flac","trackType":"flac"} Jun 29 15:12:12 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:12 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:12 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:12:12 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:12:12 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:12 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:12 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:12 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:12 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:12 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:12 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:12 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:12 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:12 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:12 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":62861,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1677 Kbps","isStreaming":false,"title":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿朵 - 生如夏花 (Live).flac","trackType":"flac"} Jun 29 15:12:12 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:12 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:12 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:12:12 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:12:12 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:12 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:12 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:12 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:12 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:12 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:12 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:12 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:12 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:12 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:12 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":62987,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1545 Kbps","isStreaming":false,"title":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿朵 - 生如夏花 (Live).flac","trackType":"flac"} Jun 29 15:12:12 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:12 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:12 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:12:12 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:12:12 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:12 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:12 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:12 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:12 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:12 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:12 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:12 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:12 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:12 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:12 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":62987,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1545 Kbps","isStreaming":false,"title":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿朵 - 生如夏花 (Live).flac","trackType":"flac"} Jun 29 15:12:12 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:12 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:12 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:12:12 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:12:12 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:12 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:12 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:12 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:12 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:12 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:12 volumio volumio[2559]: info: ------------------------------ 221ms Jun 29 15:12:12 volumio volumio[2559]: info: ------------------------------ 220ms Jun 29 15:12:12 volumio volumio[2559]: info: ------------------------------ 219ms Jun 29 15:12:14 volumio volumio[2559]: info: CoreCommandRouter::volumioPause Jun 29 15:12:14 volumio volumio[2559]: info: CoreStateMachine::pause Jun 29 15:12:14 volumio volumio[2559]: info: CoreStateMachine::stPlaybackTimer Jun 29 15:12:14 volumio volumio[2559]: info: CoreStateMachine::servicePause Jun 29 15:12:14 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:14 volumio volumio[2559]: info: CoreCommandRouter::servicePause Jun 29 15:12:14 volumio volumio[2559]: info: ControllerMpd::pause Jun 29 15:12:14 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand pause Jun 29 15:12:14 volumio volumio[2559]: info: sendMpdCommand pause took 2 milliseconds Jun 29 15:12:14 volumio volumio[2559]: info: Jun 29 15:12:14 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:14 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:14 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:14 volumio volumio[2559]: info: Jun 29 15:12:14 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:14 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:14 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:14 volumio volumio[2559]: info: Jun 29 15:12:14 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:14 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:14 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:14 volumio volumio[2559]: info: Jun 29 15:12:14 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:14 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:14 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:14 volumio volumio[2559]: info: sendMpdCommand status took 9 milliseconds Jun 29 15:12:14 volumio volumio[2559]: info: sendMpdCommand status took 8 milliseconds Jun 29 15:12:14 volumio volumio[2559]: info: sendMpdCommand status took 6 milliseconds Jun 29 15:12:14 volumio volumio[2559]: info: sendMpdCommand status took 4 milliseconds Jun 29 15:12:14 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:14 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:14 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:14 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:14 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:14 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:14 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:14 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:14 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 29 15:12:14 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 29 15:12:14 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 29 15:12:14 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 29 15:12:14 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:14 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:14 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:14 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:14 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:14 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:14 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:14 volumio volumio[2559]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":64855,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1492 Kbps","isStreaming":false,"title":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿朵 - 生如夏花 (Live).flac","trackType":"flac"} Jun 29 15:12:14 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:14 volumio volumio[2559]: info: CoreStateMachine::syncState stateService pause Jun 29 15:12:14 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus pause Jun 29 15:12:14 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:14 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:14 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:14 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:14 volumio volumio[2559]: info: CoreStateMachine::stPlaybackTimer Jun 29 15:12:14 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:14 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:14 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:14 volumio volumio[2559]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":64855,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1492 Kbps","isStreaming":false,"title":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿朵 - 生如夏花 (Live).flac","trackType":"flac"} Jun 29 15:12:14 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:14 volumio volumio[2559]: info: CoreStateMachine::syncState stateService pause Jun 29 15:12:14 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus pause Jun 29 15:12:14 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:14 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:14 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:14 volumio volumio[2559]: info: CoreStateMachine::stPlaybackTimer Jun 29 15:12:14 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:14 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:14 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:14 volumio volumio[2559]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":64855,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1492 Kbps","isStreaming":false,"title":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿朵 - 生如夏花 (Live).flac","trackType":"flac"} Jun 29 15:12:14 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:14 volumio volumio[2559]: info: CoreStateMachine::syncState stateService pause Jun 29 15:12:14 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus pause Jun 29 15:12:14 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:14 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:14 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:14 volumio volumio[2559]: info: CoreStateMachine::stPlaybackTimer Jun 29 15:12:14 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:14 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:14 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:14 volumio volumio[2559]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":64855,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1492 Kbps","isStreaming":false,"title":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿朵 - 生如夏花 (Live).flac","trackType":"flac"} Jun 29 15:12:14 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:14 volumio volumio[2559]: info: CoreStateMachine::syncState stateService pause Jun 29 15:12:14 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus pause Jun 29 15:12:14 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:14 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:14 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:14 volumio volumio[2559]: info: CoreStateMachine::stPlaybackTimer Jun 29 15:12:14 volumio volumio[2559]: info: ------------------------------ 110ms Jun 29 15:12:14 volumio volumio[2559]: info: ------------------------------ 109ms Jun 29 15:12:14 volumio volumio[2559]: info: ------------------------------ 108ms Jun 29 15:12:14 volumio volumio[2559]: info: ------------------------------ 106ms Jun 29 15:12:17 volumio volumio[2559]: info: CoreCommandRouter::volumioSeek Jun 29 15:12:17 volumio volumio[2559]: info: CoreStateMachine::seek Jun 29 15:12:17 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:17 volumio volumio[2559]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/阿朵 - 生如夏花 (Live).flac","service":"mpd","name":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=913&web=%E9%98%BF%E6%9C%B5/%E4%B9%98%E9%A3%8E%E7%A0%B4%E6%B5%AA%E7%9A%84%E5%A7%90%E5%A7%902/extralarge&path=%2FINTERNAL&metadata=false","duration":202,"trackType":"flac","samplerate":"48 kHz","bitdepth":"24 bit","channels":2} Jun 29 15:12:17 volumio volumio[2559]: info: CoreStateMachine::startPlaybackTimer Jun 29 15:12:17 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:17 volumio volumio[2559]: info: ControllerMpd::seek Jun 29 15:12:17 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:17 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:17 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:17 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:17 volumio volumio[2559]: error: null Jun 29 15:12:17 volumio volumio[2559]: info: Jun 29 15:12:17 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:17 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:17 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:17 volumio volumio[2559]: info: sendMpdCommand status took 1 milliseconds Jun 29 15:12:17 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:17 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:17 volumio volumio[2559]: info: Jun 29 15:12:17 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:17 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:17 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:17 volumio volumio[2559]: info: Jun 29 15:12:17 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:17 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:17 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:17 volumio volumio[2559]: info: Jun 29 15:12:17 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:17 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:17 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:17 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 29 15:12:17 volumio volumio[2559]: info: sendMpdCommand status took 5 milliseconds Jun 29 15:12:17 volumio volumio[2559]: info: sendMpdCommand status took 4 milliseconds Jun 29 15:12:17 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:17 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:17 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:17 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:17 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:17 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:17 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:17 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:17 volumio volumio[2559]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":105000,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1492 Kbps","isStreaming":false,"title":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿朵 - 生如夏花 (Live).flac","trackType":"flac"} Jun 29 15:12:17 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:17 volumio volumio[2559]: info: CoreStateMachine::syncState stateService pause Jun 29 15:12:17 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus pause Jun 29 15:12:17 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:17 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:17 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:17 volumio volumio[2559]: info: CoreStateMachine::stPlaybackTimer Jun 29 15:12:17 volumio volumio[2559]: info: ------------------------------ 29ms Jun 29 15:12:17 volumio volumio[2559]: info: sendMpdCommand status took 21 milliseconds Jun 29 15:12:17 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 18 milliseconds Jun 29 15:12:17 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 19 milliseconds Jun 29 15:12:17 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:17 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:17 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:17 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:17 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:17 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:17 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:17 volumio volumio[2559]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":105000,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1492 Kbps","isStreaming":false,"title":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿朵 - 生如夏花 (Live).flac","trackType":"flac"} Jun 29 15:12:17 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:17 volumio volumio[2559]: info: CoreStateMachine::syncState stateService pause Jun 29 15:12:17 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus pause Jun 29 15:12:17 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:17 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:17 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:17 volumio volumio[2559]: info: CoreStateMachine::stPlaybackTimer Jun 29 15:12:17 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:17 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:17 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:17 volumio volumio[2559]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":105000,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1492 Kbps","isStreaming":false,"title":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿朵 - 生如夏花 (Live).flac","trackType":"flac"} Jun 29 15:12:17 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:17 volumio volumio[2559]: info: CoreStateMachine::syncState stateService pause Jun 29 15:12:17 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus pause Jun 29 15:12:17 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:17 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:17 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:17 volumio volumio[2559]: info: CoreStateMachine::stPlaybackTimer Jun 29 15:12:17 volumio volumio[2559]: info: ------------------------------ 80ms Jun 29 15:12:17 volumio volumio[2559]: info: ------------------------------ 81ms Jun 29 15:12:17 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 63 milliseconds Jun 29 15:12:17 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:17 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:17 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:17 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:17 volumio volumio[2559]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":105000,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1492 Kbps","isStreaming":false,"title":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿朵 - 生如夏花 (Live).flac","trackType":"flac"} Jun 29 15:12:17 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:17 volumio volumio[2559]: info: CoreStateMachine::syncState stateService pause Jun 29 15:12:17 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus pause Jun 29 15:12:17 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:17 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:17 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:17 volumio volumio[2559]: info: CoreStateMachine::stPlaybackTimer Jun 29 15:12:17 volumio volumio[2559]: info: ------------------------------ 113ms Jun 29 15:12:20 volumio volumio[2559]: info: CoreCommandRouter::volumioPlay Jun 29 15:12:20 volumio volumio[2559]: info: CoreStateMachine::play index undefined Jun 29 15:12:20 volumio volumio[2559]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 29 15:12:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:20 volumio volumio[2559]: info: CoreStateMachine::startPlaybackTimer Jun 29 15:12:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:20 volumio volumio[2559]: info: ControllerMpd::resume Jun 29 15:12:20 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand play Jun 29 15:12:20 volumio volumio[2559]: info: sendMpdCommand play took 51 milliseconds Jun 29 15:12:20 volumio volumio[2559]: info: Jun 29 15:12:20 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:20 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:20 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:20 volumio volumio[2559]: info: Jun 29 15:12:20 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:20 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:20 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:20 volumio volumio[2559]: info: Jun 29 15:12:20 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:20 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:20 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:20 volumio volumio[2559]: info: Jun 29 15:12:20 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:20 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:20 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:20 volumio volumio[2559]: info: sendMpdCommand status took 12 milliseconds Jun 29 15:12:20 volumio volumio[2559]: info: sendMpdCommand status took 11 milliseconds Jun 29 15:12:20 volumio volumio[2559]: info: sendMpdCommand status took 9 milliseconds Jun 29 15:12:20 volumio volumio[2559]: info: sendMpdCommand status took 6 milliseconds Jun 29 15:12:20 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:20 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:20 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:20 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:20 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:20 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:20 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:20 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:20 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 29 15:12:20 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 29 15:12:20 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 29 15:12:20 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:20 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:20 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:20 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:20 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:20 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":105461,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1732 Kbps","isStreaming":false,"title":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿朵 - 生如夏花 (Live).flac","trackType":"flac"} Jun 29 15:12:20 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:20 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:20 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus pause Jun 29 15:12:20 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:20 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:20 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:20 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:20 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:20 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":105860,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1744 Kbps","isStreaming":false,"title":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿朵 - 生如夏花 (Live).flac","trackType":"flac"} Jun 29 15:12:20 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:20 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:20 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:12:20 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:12:20 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:20 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:20 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:20 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:20 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:20 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:20 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:20 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":105986,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1744 Kbps","isStreaming":false,"title":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿朵 - 生如夏花 (Live).flac","trackType":"flac"} Jun 29 15:12:20 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:20 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:20 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:12:20 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:12:20 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:20 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:20 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:20 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:20 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:20 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:20 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:20 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":105986,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1852 Kbps","isStreaming":false,"title":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿朵 - 生如夏花 (Live).flac","trackType":"flac"} Jun 29 15:12:20 volumio volumio[2559]: verbose: CURRENT POSITION 1 Jun 29 15:12:20 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:20 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:12:20 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:12:20 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:20 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:20 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:20 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:20 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:20 volumio volumio[2559]: info: ------------------------------ 179ms Jun 29 15:12:20 volumio volumio[2559]: info: ------------------------------ 179ms Jun 29 15:12:20 volumio volumio[2559]: info: ------------------------------ 177ms Jun 29 15:12:20 volumio volumio[2559]: info: ------------------------------ 175ms Jun 29 15:12:22 volumio volumio[2559]: info: VolumeController::SetAlsaVolume50 Jun 29 15:12:22 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:22 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:22 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:22 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:22 volumio volumio[2559]: info: VolumeController::SetAlsaVolume51 Jun 29 15:12:22 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:22 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:22 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:22 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:23 volumio kernel: hwmon hwmon1: Voltage normalised Jun 29 15:12:24 volumio volumio[2559]: info: VolumeController::SetAlsaVolume52 Jun 29 15:12:24 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:24 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:24 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:24 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:24 volumio volumio[2559]: info: VolumeController::SetAlsaVolume53 Jun 29 15:12:25 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:25 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:25 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:25 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:25 volumio volumio[2559]: info: VolumeController::SetAlsaVolume54 Jun 29 15:12:25 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:25 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:25 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:25 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:25 volumio volumio[2559]: info: VolumeController::SetAlsaVolume55 Jun 29 15:12:25 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:25 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:25 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:25 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:25 volumio kernel: hwmon hwmon1: Undervoltage detected! Jun 29 15:12:25 volumio volumio[2559]: info: VolumeController::SetAlsaVolume56 Jun 29 15:12:25 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:25 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:25 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:25 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:25 volumio volumio[2559]: info: VolumeController::SetAlsaVolume57 Jun 29 15:12:25 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:25 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:25 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:25 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:26 volumio volumio[2559]: info: VolumeController::SetAlsaVolume58 Jun 29 15:12:26 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:26 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:26 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:26 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:26 volumio volumio[2559]: info: VolumeController::SetAlsaVolume59 Jun 29 15:12:26 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:26 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:26 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:26 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:26 volumio volumio[2559]: info: VolumeController::SetAlsaVolume60 Jun 29 15:12:26 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:26 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:26 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:26 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:26 volumio volumio[2559]: info: VolumeController::SetAlsaVolume61 Jun 29 15:12:26 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:26 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:26 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:26 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioNext Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::next Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::stop Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::stPlaybackTimer Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::updateTrackBlock Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrackBlock Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::serviceStop Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 1 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::serviceStop Jun 29 15:12:29 volumio volumio[2559]: info: ControllerMpd::stop Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand stop Jun 29 15:12:29 volumio volumio[2559]: info: Jun 29 15:12:29 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:29 volumio volumio[2559]: info: sendMpdCommand stop took 10 milliseconds Jun 29 15:12:29 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::play index undefined Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::startPlaybackTimer Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/阿兰 - 红颜旧 (Live).flac Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand stop Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::updateTrackBlock Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrackBlock Jun 29 15:12:29 volumio volumio[2559]: info: sendMpdCommand status took 4 milliseconds Jun 29 15:12:29 volumio volumio[2559]: info: sendMpdCommand stop took 2 milliseconds Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand clear Jun 29 15:12:29 volumio volumio[2559]: info: Jun 29 15:12:29 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:29 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:29 volumio volumio[2559]: info: Jun 29 15:12:29 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:29 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:29 volumio volumio[2559]: info: Jun 29 15:12:29 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:29 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:29 volumio volumio[2559]: info: Jun 29 15:12:29 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:12:29 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:12:29 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 29 15:12:29 volumio volumio[2559]: info: sendMpdCommand clear took 7 milliseconds Jun 29 15:12:29 volumio volumio[2559]: info: sendMpdCommand status took 5 milliseconds Jun 29 15:12:29 volumio volumio[2559]: info: sendMpdCommand status took 4 milliseconds Jun 29 15:12:29 volumio volumio[2559]: info: sendMpdCommand status took 3 milliseconds Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/阿兰 - 红颜旧 (Live).flac" Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:29 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"生如夏花 (Live)","artist":"阿朵","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿朵 - 生如夏花 (Live).flac","trackType":"flac"} Jun 29 15:12:29 volumio volumio[2559]: verbose: CURRENT POSITION 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::syncState stateService stop Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus stop Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: No code Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: 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} Jun 29 15:12:29 volumio volumio[2559]: verbose: CURRENT POSITION 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::syncState stateService stop Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus stop Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: No code Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: 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} Jun 29 15:12:29 volumio volumio[2559]: verbose: CURRENT POSITION 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::syncState stateService stop Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus stop Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: No code Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: 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} Jun 29 15:12:29 volumio volumio[2559]: verbose: CURRENT POSITION 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::syncState stateService stop Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus stop Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: No code Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: ------------------------------ 139ms Jun 29 15:12:29 volumio volumio[2559]: info: ------------------------------ 132ms Jun 29 15:12:29 volumio volumio[2559]: info: ------------------------------ 131ms Jun 29 15:12:29 volumio volumio[2559]: info: ------------------------------ 131ms Jun 29 15:12:29 volumio volumio[2559]: info: Jun 29 15:12:29 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:12:29 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:12:29 volumio volumio[2559]: info: Jun 29 15:12:29 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:12:29 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:12:29 volumio volumio[2559]: info: Jun 29 15:12:29 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:12:29 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:12:29 volumio volumio[2559]: error: updateQueue error: null Jun 29 15:12:29 volumio volumio[2559]: info: Jun 29 15:12:29 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:12:29 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:12:29 volumio volumio[2559]: info: ------------------------------ 135ms Jun 29 15:12:29 volumio volumio[2559]: info: sendMpdCommand add "INTERNAL/阿兰 - 红颜旧 (Live).flac" took 133 milliseconds Jun 29 15:12:29 volumio volumio[2559]: info: ------------------------------ 6ms Jun 29 15:12:29 volumio volumio[2559]: info: ------------------------------ 5ms Jun 29 15:12:29 volumio volumio[2559]: info: ------------------------------ 4ms Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand play Jun 29 15:12:29 volumio volumio[2559]: info: Jun 29 15:12:29 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:12:29 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:12:29 volumio volumio[2559]: info: Jun 29 15:12:29 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:12:29 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:12:29 volumio volumio[2559]: info: Jun 29 15:12:29 volumio volumio[2559]: ---------------------------- MPD announces system playlist update Jun 29 15:12:29 volumio volumio[2559]: info: Ignoring MPD Status Update Jun 29 15:12:29 volumio volumio[2559]: info: Jun 29 15:12:29 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:29 volumio volumio[2559]: info: ------------------------------ 18ms Jun 29 15:12:29 volumio volumio[2559]: info: sendMpdCommand play took 17 milliseconds Jun 29 15:12:29 volumio volumio[2559]: info: ------------------------------ 16ms Jun 29 15:12:29 volumio volumio[2559]: info: ------------------------------ 15ms Jun 29 15:12:29 volumio volumio[2559]: info: ------------------------------ 14ms Jun 29 15:12:29 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:29 volumio volumio[2559]: info: Jun 29 15:12:29 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:29 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:29 volumio volumio[2559]: info: Jun 29 15:12:29 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:29 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:29 volumio volumio[2559]: info: Jun 29 15:12:29 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:29 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:29 volumio volumio[2559]: info: Jun 29 15:12:29 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:29 volumio volumio[2559]: info: sendMpdCommand status took 14 milliseconds Jun 29 15:12:29 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:29 volumio volumio[2559]: info: sendMpdCommand status took 14 milliseconds Jun 29 15:12:29 volumio volumio[2559]: info: sendMpdCommand status took 13 milliseconds Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:29 volumio volumio[2559]: info: Jun 29 15:12:29 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:29 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:29 volumio volumio[2559]: info: Jun 29 15:12:29 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:29 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:29 volumio volumio[2559]: info: Jun 29 15:12:29 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:29 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:29 volumio volumio[2559]: info: sendMpdCommand status took 29 milliseconds Jun 29 15:12:29 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 29 milliseconds Jun 29 15:12:29 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 28 milliseconds Jun 29 15:12:29 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 27 milliseconds Jun 29 15:12:29 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 28 milliseconds Jun 29 15:12:29 volumio volumio[2559]: info: sendMpdCommand status took 7 milliseconds Jun 29 15:12:29 volumio volumio[2559]: info: sendMpdCommand status took 6 milliseconds Jun 29 15:12:29 volumio volumio[2559]: info: sendMpdCommand status took 5 milliseconds Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:29 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"红颜旧 (Live)","artist":"阿兰","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿兰 - 红颜旧 (Live).flac","trackType":"flac"} Jun 29 15:12:29 volumio volumio[2559]: verbose: CURRENT POSITION 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus stop Jun 29 15:12:29 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"红颜旧 (Live)","artist":"阿兰","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿兰 - 红颜旧 (Live).flac","trackType":"flac"} Jun 29 15:12:29 volumio volumio[2559]: verbose: CURRENT POSITION 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:12:29 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"红颜旧 (Live)","artist":"阿兰","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿兰 - 红颜旧 (Live).flac","trackType":"flac"} Jun 29 15:12:29 volumio volumio[2559]: verbose: CURRENT POSITION 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:12:29 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"红颜旧 (Live)","artist":"阿兰","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿兰 - 红颜旧 (Live).flac","trackType":"flac"} Jun 29 15:12:29 volumio volumio[2559]: verbose: CURRENT POSITION 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:12:29 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: ------------------------------ 79ms Jun 29 15:12:29 volumio volumio[2559]: info: ------------------------------ 125ms Jun 29 15:12:29 volumio volumio[2559]: info: ------------------------------ 125ms Jun 29 15:12:29 volumio volumio[2559]: info: ------------------------------ 125ms Jun 29 15:12:29 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 82 milliseconds Jun 29 15:12:29 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 81 milliseconds Jun 29 15:12:29 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 81 milliseconds Jun 29 15:12:29 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 80 milliseconds Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:29 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:29 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"红颜旧 (Live)","artist":"阿兰","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿兰 - 红颜旧 (Live).flac","trackType":"flac"} Jun 29 15:12:29 volumio volumio[2559]: verbose: CURRENT POSITION 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:12:29 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":230,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1525 Kbps","isStreaming":false,"title":"红颜旧 (Live)","artist":"阿兰","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿兰 - 红颜旧 (Live).flac","trackType":"flac"} Jun 29 15:12:29 volumio volumio[2559]: verbose: CURRENT POSITION 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:12:29 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":230,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1525 Kbps","isStreaming":false,"title":"红颜旧 (Live)","artist":"阿兰","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿兰 - 红颜旧 (Live).flac","trackType":"flac"} Jun 29 15:12:29 volumio volumio[2559]: verbose: CURRENT POSITION 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:12:29 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":230,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1525 Kbps","isStreaming":false,"title":"红颜旧 (Live)","artist":"阿兰","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿兰 - 红颜旧 (Live).flac","trackType":"flac"} Jun 29 15:12:29 volumio volumio[2559]: verbose: CURRENT POSITION 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:12:29 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:29 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:29 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:29 volumio volumio[2559]: info: ------------------------------ 197ms Jun 29 15:12:29 volumio volumio[2559]: info: ------------------------------ 174ms Jun 29 15:12:29 volumio volumio[2559]: info: ------------------------------ 172ms Jun 29 15:12:29 volumio volumio[2559]: info: ------------------------------ 171ms Jun 29 15:12:30 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 29 15:12:30 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 29 15:12:30 volumio volumio[2559]: info: Discovery: Getting this device information Jun 29 15:12:30 volumio volumio[2559]: info: CoreCommandRouter::volumioGetState Jun 29 15:12:30 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:30 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 29 15:12:31 volumio volumio[2559]: info: CoreCommandRouter::volumioSeek Jun 29 15:12:31 volumio volumio[2559]: info: CoreStateMachine::seek Jun 29 15:12:31 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:31 volumio volumio[2559]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/阿兰 - 红颜旧 (Live).flac","service":"mpd","name":"红颜旧 (Live)","artist":"阿兰","album":"乘风破浪的姐姐2","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=913&web=%E9%98%BF%E5%85%B0/%E4%B9%98%E9%A3%8E%E7%A0%B4%E6%B5%AA%E7%9A%84%E5%A7%90%E5%A7%902/extralarge&path=%2FINTERNAL&metadata=false","duration":230,"trackType":"flac","samplerate":"48 kHz","bitdepth":"24 bit","channels":2} Jun 29 15:12:31 volumio volumio[2559]: info: CoreStateMachine::startPlaybackTimer Jun 29 15:12:31 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:31 volumio volumio[2559]: info: ControllerMpd::seek Jun 29 15:12:31 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:31 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:31 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 15:12:31 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:31 volumio volumio[2559]: error: null Jun 29 15:12:31 volumio volumio[2559]: info: Jun 29 15:12:31 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:31 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:31 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:31 volumio volumio[2559]: info: Jun 29 15:12:31 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:31 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:31 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:31 volumio volumio[2559]: info: Jun 29 15:12:31 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:31 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:31 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:31 volumio volumio[2559]: info: Jun 29 15:12:31 volumio volumio[2559]: ---------------------------- MPD announces state update: player Jun 29 15:12:31 volumio volumio[2559]: info: ControllerMpd::getState Jun 29 15:12:31 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand status Jun 29 15:12:31 volumio volumio[2559]: info: sendMpdCommand status took 9 milliseconds Jun 29 15:12:31 volumio volumio[2559]: info: sendMpdCommand status took 5 milliseconds Jun 29 15:12:31 volumio volumio[2559]: info: sendMpdCommand status took 4 milliseconds Jun 29 15:12:31 volumio volumio[2559]: info: sendMpdCommand status took 2 milliseconds Jun 29 15:12:31 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:31 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:31 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:31 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:31 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:31 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:31 volumio volumio[2559]: verbose: ControllerMpd::parseState Jun 29 15:12:31 volumio volumio[2559]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 15:12:31 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 29 15:12:31 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 29 15:12:31 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 29 15:12:31 volumio volumio[2559]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 29 15:12:31 volumio volumio[2559]: verbose: ControllerMpd::parseTrackInfo Jun 29 15:12:31 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:31 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:31 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:31 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":110000,"duration":230,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1448 Kbps","isStreaming":false,"title":"红颜旧 (Live)","artist":"阿兰","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿兰 - 红颜旧 (Live).flac","trackType":"flac"} Jun 29 15:12:31 volumio volumio[2559]: verbose: CURRENT POSITION 2 Jun 29 15:12:31 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:31 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:12:31 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:12:31 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:31 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:31 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:31 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:31 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:31 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:31 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:31 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:31 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:31 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":110000,"duration":230,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1448 Kbps","isStreaming":false,"title":"红颜旧 (Live)","artist":"阿兰","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿兰 - 红颜旧 (Live).flac","trackType":"flac"} Jun 29 15:12:31 volumio volumio[2559]: verbose: CURRENT POSITION 2 Jun 29 15:12:31 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:31 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:12:31 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:12:31 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:31 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:31 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:31 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:31 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:31 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:31 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:31 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:31 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:31 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":110000,"duration":230,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1448 Kbps","isStreaming":false,"title":"红颜旧 (Live)","artist":"阿兰","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿兰 - 红颜旧 (Live).flac","trackType":"flac"} Jun 29 15:12:31 volumio volumio[2559]: verbose: CURRENT POSITION 2 Jun 29 15:12:31 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:31 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:12:31 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:12:31 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:31 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:31 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:31 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:31 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:31 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:31 volumio volumio[2559]: info: ControllerMpd::pushState Jun 29 15:12:31 volumio volumio[2559]: info: CoreCommandRouter::servicePushState Jun 29 15:12:31 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:31 volumio volumio[2559]: verbose: STATE SERVICE {"status":"play","position":0,"seek":110000,"duration":230,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1448 Kbps","isStreaming":false,"title":"红颜旧 (Live)","artist":"阿兰","album":"乘风破浪的姐姐2","uri":"INTERNAL/阿兰 - 红颜旧 (Live).flac","trackType":"flac"} Jun 29 15:12:31 volumio volumio[2559]: verbose: CURRENT POSITION 2 Jun 29 15:12:31 volumio volumio[2559]: info: CoreStateMachine::syncState stateService play Jun 29 15:12:31 volumio volumio[2559]: info: CoreStateMachine::syncState currentStatus play Jun 29 15:12:31 volumio volumio[2559]: info: Received an update from plugin. extracting info from payload Jun 29 15:12:31 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:31 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:31 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:31 volumio volumio[2559]: info: CoreStateMachine::pushState Jun 29 15:12:31 volumio volumio[2559]: info: CorePlayQueue::getTrack 2 Jun 29 15:12:31 volumio volumio[2559]: info: CoreCommandRouter::volumioPushState Jun 29 15:12:31 volumio volumio[2559]: info: ------------------------------ 194ms Jun 29 15:12:31 volumio volumio[2559]: info: ------------------------------ 192ms Jun 29 15:12:31 volumio volumio[2559]: info: ------------------------------ 191ms Jun 29 15:12:31 volumio volumio[2559]: info: ------------------------------ 191ms Jun 29 15:12:32 volumio volumio[2559]: info: CoreCommandRouter::volumioGetQueue Jun 29 15:12:32 volumio volumio[2559]: info: CoreStateMachine::getQueue Jun 29 15:12:32 volumio volumio[2559]: info: CorePlayQueue::getQueue Jun 29 15:12:36 volumio volumio[2559]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 29 15:12:36 volumio volumio[2559]: info: Retrying Login Due to Network Error Jun 29 15:12:37 volumio volumio[2559]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 29 15:12:37 volumio volumio[2559]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 29 15:12:37 volumio volumio[2559]: TypeError: Cannot read property 'length' of undefined Jun 29 15:12:37 volumio volumio[2559]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jun 29 15:12:37 volumio volumio[2559]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jun 29 15:12:37 volumio volumio[2559]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jun 29 15:12:37 volumio volumio[2559]: at Parser.emit (events.js:315:20) Jun 29 15:12:37 volumio volumio[2559]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jun 29 15:12:37 volumio volumio[2559]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jun 29 15:12:37 volumio volumio[2559]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jun 29 15:12:37 volumio volumio[2559]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jun 29 15:12:37 volumio volumio[2559]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jun 29 15:12:37 volumio volumio[2559]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jun 29 15:12:37 volumio volumio[2559]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jun 29 15:12:37 volumio volumio[2559]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jun 29 15:12:37 volumio volumio[2559]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jun 29 15:12:37 volumio volumio[2559]: at IncomingMessage.emit (events.js:327:22) Jun 29 15:12:37 volumio volumio[2559]: at endReadableNT (internal/streams/readable.js:1327:12) Jun 29 15:12:37 volumio volumio[2559]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 29 15:12:37 volumio volumio[2559]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 29 15:12:38 volumio sudo[3537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-29 15:11 Jun 29 15:12:38 volumio sudo[3537]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"