-- Logs begin at Sat 2025-08-30 13:10:04 CST, end at Sat 2025-08-30 14:43:49 CST. -- Aug 30 14:42:00 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:00 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:00 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:00 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:00 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:00 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:00 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:00 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:00 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:00 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:00 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:00 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:00 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:01 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:01 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:01 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:01 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:01 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:01 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:01 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:01 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:01 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:01 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:01 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:01 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:01 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:01 volumio-rpi volumio[25936]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Aug 30 14:42:01 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Aug 30 14:42:01 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 14:42:01 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 14:42:01 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 14:42:01 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 14:42:01 volumio-rpi wpa_supplicant[881]: wlan0: Failed to initiate sched scan Aug 30 14:42:01 volumio-rpi sudo[29562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 14:42:01 volumio-rpi sudo[29564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 14:42:01 volumio-rpi sudo[29564]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 14:42:01 volumio-rpi sudo[29562]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 14:42:01 volumio-rpi sudo[29562]: pam_unix(sudo:session): session closed for user root Aug 30 14:42:01 volumio-rpi volumio[25936]: info: MPD Permissions set Aug 30 14:42:01 volumio-rpi systemd[1]: Stopping Music Player Daemon... Aug 30 14:42:01 volumio-rpi systemd[1]: mpd.service: Succeeded. Aug 30 14:42:01 volumio-rpi systemd[1]: Stopped Music Player Daemon. Aug 30 14:42:01 volumio-rpi systemd[1]: Starting Music Player Daemon... Aug 30 14:42:02 volumio-rpi sudo[29572]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 14:42:02 volumio-rpi sudo[29572]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 14:42:02 volumio-rpi sudo[29572]: pam_unix(sudo:session): session closed for user root Aug 30 14:42:02 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:02 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:02 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:02 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:02 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:02 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:02 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:02 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:02 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:02 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:02 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:02 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:02 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:02 volumio-rpi shairport-sync[26528]: Very large sync error: 1444140306 frames, with delay: 1444239883, td_in_frames: 16432, rt: 6295315612, nt: 6295238876, current_delay: 1444146715, seqno: 18247, given timestamp: 2000271580. Aug 30 14:42:02 volumio-rpi shairport-sync[26528]: Very large sync error: 1444140307 frames, with delay: 1444239884, td_in_frames: 16874, rt: 6295315612, nt: 6295239228, current_delay: 1444146626, seqno: 18248, given timestamp: 2000271932. Aug 30 14:42:02 volumio-rpi shairport-sync[26528]: Very large sync error: 1444140324 frames, with delay: 1444239901, td_in_frames: 17376, rt: 6295315612, nt: 6295239580, current_delay: 1444146493, seqno: 18249, given timestamp: 2000272284. Aug 30 14:42:02 volumio-rpi shairport-sync[26528]: Very large sync error: 1444140292 frames, with delay: 1444239869, td_in_frames: 17433, rt: 6295315612, nt: 6295239932, current_delay: 1444146756, seqno: 18250, given timestamp: 2000272636. Aug 30 14:42:02 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1444140292 -- setting it to 132300. Aug 30 14:42:03 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:03 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:03 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:03 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:03 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:03 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:03 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:03 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:03 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:03 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:03 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:03 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:03 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:03 volumio-rpi mpd[29588]: Aug 30 14:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 30 14:42:03 volumio-rpi systemd[1]: Started Music Player Daemon. Aug 30 14:42:03 volumio-rpi sudo[29564]: pam_unix(sudo:session): session closed for user root Aug 30 14:42:03 volumio-rpi volumio[25936]: error: updateQueue error: null Aug 30 14:42:04 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:04 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:04 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:04 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:04 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:04 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:04 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:04 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:04 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:04 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:04 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:04 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:04 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:05 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:05 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:05 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:05 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:05 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:05 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:05 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:05 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:05 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:05 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:05 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:05 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:05 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:05 volumio-rpi shairport-sync[26528]: Very large sync error: 1444007866 frames, with delay: 1444107443, td_in_frames: 16793, rt: 6295449020, nt: 6295372636, current_delay: 1444014266, seqno: 18627, given timestamp: 2000405340. Aug 30 14:42:05 volumio-rpi shairport-sync[26528]: Very large sync error: 1444007877 frames, with delay: 1444107454, td_in_frames: 17333, rt: 6295449020, nt: 6295372988, current_delay: 1444014089, seqno: 18628, given timestamp: 2000405692. Aug 30 14:42:05 volumio-rpi shairport-sync[26528]: Very large sync error: 1444007843 frames, with delay: 1444107420, td_in_frames: 17388, rt: 6295449020, nt: 6295373340, current_delay: 1444014352, seqno: 18629, given timestamp: 2000406044. Aug 30 14:42:05 volumio-rpi shairport-sync[26528]: Very large sync error: 1444007868 frames, with delay: 1444107445, td_in_frames: 17723, rt: 6295449020, nt: 6295373692, current_delay: 1444014394, seqno: 18630, given timestamp: 2000406396. Aug 30 14:42:05 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1444007868 -- setting it to 132300. Aug 30 14:42:06 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:06 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:06 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:06 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:06 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:06 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:06 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:06 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:06 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:06 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:06 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:06 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:06 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:07 volumio-rpi wpa_supplicant[881]: wlan0: Failed to initiate sched scan Aug 30 14:42:07 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:07 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:07 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:07 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:07 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:07 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:07 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:07 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:07 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:07 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:07 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:07 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:07 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:08 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:08 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:08 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:08 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:08 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:08 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:08 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:08 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:08 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:08 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:08 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:08 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:08 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:08 volumio-rpi shairport-sync[26528]: Very large sync error: 1443875395 frames, with delay: 1443974972, td_in_frames: 17432, rt: 6295582076, nt: 6295506396, current_delay: 1443881860, seqno: 19007, given timestamp: 2000539100. Aug 30 14:42:08 volumio-rpi shairport-sync[26528]: Very large sync error: 1443875390 frames, with delay: 1443974967, td_in_frames: 17868, rt: 6295582076, nt: 6295506748, current_delay: 1443881771, seqno: 19008, given timestamp: 2000539452. Aug 30 14:42:08 volumio-rpi shairport-sync[26528]: Very large sync error: 1443875421 frames, with delay: 1443974998, td_in_frames: 18384, rt: 6295582076, nt: 6295507100, current_delay: 1443881638, seqno: 19009, given timestamp: 2000539804. Aug 30 14:42:08 volumio-rpi shairport-sync[26528]: Very large sync error: 1443875415 frames, with delay: 1443974992, td_in_frames: 18423, rt: 6295582076, nt: 6295507452, current_delay: 1443881945, seqno: 19010, given timestamp: 2000540156. Aug 30 14:42:08 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1443875415 -- setting it to 132300. Aug 30 14:42:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:09 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:09 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:09 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:09 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:09 volumio-rpi volumio[25936]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Aug 30 14:42:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Aug 30 14:42:09 volumio-rpi sudo[29722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 14:42:09 volumio-rpi sudo[29722]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 14:42:09 volumio-rpi sudo[29722]: pam_unix(sudo:session): session closed for user root Aug 30 14:42:09 volumio-rpi volumio[25936]: info: MPD Permissions set Aug 30 14:42:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 14:42:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 14:42:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 14:42:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 14:42:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 14:42:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 14:42:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 14:42:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 14:42:09 volumio-rpi sudo[29725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 14:42:09 volumio-rpi sudo[29725]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 14:42:09 volumio-rpi systemd[1]: Stopping Music Player Daemon... Aug 30 14:42:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 14:42:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 14:42:10 volumio-rpi systemd[1]: mpd.service: Succeeded. Aug 30 14:42:10 volumio-rpi systemd[1]: Stopped Music Player Daemon. Aug 30 14:42:10 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:10 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:10 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:10 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:10 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:10 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:10 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:10 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:10 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:10 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:10 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:10 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:10 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:10 volumio-rpi systemd[1]: Starting Music Player Daemon... Aug 30 14:42:10 volumio-rpi sudo[29731]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 14:42:10 volumio-rpi sudo[29731]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 14:42:10 volumio-rpi sudo[29731]: pam_unix(sudo:session): session closed for user root Aug 30 14:42:11 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:11 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:11 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:11 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:11 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:11 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:11 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:11 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:11 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:11 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:11 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:11 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:11 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:11 volumio-rpi shairport-sync[26528]: Very large sync error: 1443742942 frames, with delay: 1443842519, td_in_frames: 17560, rt: 6295715836, nt: 6295640156, current_delay: 1443749279, seqno: 19387, given timestamp: 2000672860. Aug 30 14:42:11 volumio-rpi shairport-sync[26528]: Very large sync error: 1443742929 frames, with delay: 1443842506, td_in_frames: 18076, rt: 6295715836, nt: 6295640508, current_delay: 1443749102, seqno: 19388, given timestamp: 2000673212. Aug 30 14:42:11 volumio-rpi shairport-sync[26528]: Very large sync error: 1443742930 frames, with delay: 1443842507, td_in_frames: 18122, rt: 6295715836, nt: 6295640860, current_delay: 1443749409, seqno: 19389, given timestamp: 2000673564. Aug 30 14:42:11 volumio-rpi shairport-sync[26528]: Very large sync error: 1443742932 frames, with delay: 1443842509, td_in_frames: 18609, rt: 6295715836, nt: 6295641212, current_delay: 1443749276, seqno: 19390, given timestamp: 2000673916. Aug 30 14:42:11 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1443742932 -- setting it to 132300. Aug 30 14:42:12 volumio-rpi mpd[29751]: Aug 30 14:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 30 14:42:12 volumio-rpi systemd[1]: Started Music Player Daemon. Aug 30 14:42:12 volumio-rpi sudo[29725]: pam_unix(sudo:session): session closed for user root Aug 30 14:42:12 volumio-rpi volumio[25936]: error: updateQueue error: null Aug 30 14:42:12 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:12 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:12 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:12 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:12 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:12 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:12 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:12 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:12 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:12 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:12 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:12 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:12 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:12 volumio-rpi wpa_supplicant[881]: wlan0: Failed to initiate sched scan Aug 30 14:42:13 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:13 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:13 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:13 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:13 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:13 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:13 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:13 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:13 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:13 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:13 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:13 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:13 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:14 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:14 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:14 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:14 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:14 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:14 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:14 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:14 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:14 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:14 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:14 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:14 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:14 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:14 volumio-rpi shairport-sync[26528]: Very large sync error: 1443610497 frames, with delay: 1443710074, td_in_frames: 17388, rt: 6295849948, nt: 6295773916, current_delay: 1443616654, seqno: 19767, given timestamp: 2000806620. Aug 30 14:42:14 volumio-rpi shairport-sync[26528]: Very large sync error: 1443610287 frames, with delay: 1443709864, td_in_frames: 17444, rt: 6295849948, nt: 6295774268, current_delay: 1443616740, seqno: 19768, given timestamp: 2000806972. Aug 30 14:42:14 volumio-rpi shairport-sync[26528]: Very large sync error: 1443610278 frames, with delay: 1443709855, td_in_frames: 17833, rt: 6295849948, nt: 6295774620, current_delay: 1443616694, seqno: 19769, given timestamp: 2000807324. Aug 30 14:42:14 volumio-rpi shairport-sync[26528]: Very large sync error: 1443610260 frames, with delay: 1443709837, td_in_frames: 18344, rt: 6295849948, nt: 6295774972, current_delay: 1443616517, seqno: 19770, given timestamp: 2000807676. Aug 30 14:42:14 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1443610260 -- setting it to 132300. Aug 30 14:42:15 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:15 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:15 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:15 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:15 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:15 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:15 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:15 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:15 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:15 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:15 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:15 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:15 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:16 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:16 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:16 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:16 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:16 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:16 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:16 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:16 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:16 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:16 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:16 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:16 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:16 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:17 volumio-rpi volumio[25936]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Aug 30 14:42:17 volumio-rpi volumio[25936]: info: Retrying Login Due to Network Error Aug 30 14:42:17 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:17 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:17 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:17 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:17 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:17 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:17 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:17 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:17 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:17 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:17 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:17 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:17 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:17 volumio-rpi shairport-sync[26528]: Very large sync error: 1443477806 frames, with delay: 1443577383, td_in_frames: 17324, rt: 6295983708, nt: 6295907676, current_delay: 1443484027, seqno: 20147, given timestamp: 2000940380. Aug 30 14:42:17 volumio-rpi shairport-sync[26528]: Very large sync error: 1443477833 frames, with delay: 1443577410, td_in_frames: 17352, rt: 6295983708, nt: 6295908028, current_delay: 1443484378, seqno: 20148, given timestamp: 2000940732. Aug 30 14:42:17 volumio-rpi shairport-sync[26528]: Very large sync error: 1443477820 frames, with delay: 1443577397, td_in_frames: 17780, rt: 6295983708, nt: 6295908380, current_delay: 1443484289, seqno: 20149, given timestamp: 2000941084. Aug 30 14:42:17 volumio-rpi shairport-sync[26528]: Very large sync error: 1443477804 frames, with delay: 1443577381, td_in_frames: 18293, rt: 6295983708, nt: 6295908732, current_delay: 1443484112, seqno: 20150, given timestamp: 2000941436. Aug 30 14:42:18 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1443477804 -- setting it to 132300. Aug 30 14:42:18 volumio-rpi wpa_supplicant[881]: wlan0: Failed to initiate sched scan Aug 30 14:42:18 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:18 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:18 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:18 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:18 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:18 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:18 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:18 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:18 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:18 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:18 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:18 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:18 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:19 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:19 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:19 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:19 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:19 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:19 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:19 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:19 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:19 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:19 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:19 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:19 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:19 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:20 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:20 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:20 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:20 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:20 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:20 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:20 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:20 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:20 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:20 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:20 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:20 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:20 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:20 volumio-rpi shairport-sync[26528]: Very large sync error: 1443345381 frames, with delay: 1443444958, td_in_frames: 17744, rt: 6296116764, nt: 6296041436, current_delay: 1443351886, seqno: 20527, given timestamp: 2001074140. Aug 30 14:42:21 volumio-rpi shairport-sync[26528]: Very large sync error: 1443345366 frames, with delay: 1443444943, td_in_frames: 18258, rt: 6296116764, nt: 6296041788, current_delay: 1443351709, seqno: 20528, given timestamp: 2001074492. Aug 30 14:42:21 volumio-rpi shairport-sync[26528]: Very large sync error: 1443345374 frames, with delay: 1443444951, td_in_frames: 18663, rt: 6296116764, nt: 6296042140, current_delay: 1443351664, seqno: 20529, given timestamp: 2001074844. Aug 30 14:42:21 volumio-rpi shairport-sync[26528]: Very large sync error: 1443345386 frames, with delay: 1443444963, td_in_frames: 19160, rt: 6296116764, nt: 6296042492, current_delay: 1443351531, seqno: 20530, given timestamp: 2001075196. Aug 30 14:42:21 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1443345386 -- setting it to 132300. Aug 30 14:42:21 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:21 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:21 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:21 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:21 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:21 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:21 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:21 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:21 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:21 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:21 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:21 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:21 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:22 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:22 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:22 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:22 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:22 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:22 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:22 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:22 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:22 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:22 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:22 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:22 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:22 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:23 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioGetState Aug 30 14:42:23 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:23 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:23 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:23 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:23 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:23 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:23 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:23 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:23 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:23 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:23 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:23 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:23 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:23 volumio-rpi wpa_supplicant[881]: wlan0: Failed to initiate sched scan Aug 30 14:42:24 volumio-rpi shairport-sync[26528]: Very large sync error: 1443212897 frames, with delay: 1443312474, td_in_frames: 18193, rt: 6296250172, nt: 6296175196, current_delay: 1443219305, seqno: 20907, given timestamp: 2001207900. Aug 30 14:42:24 volumio-rpi shairport-sync[26528]: Very large sync error: 1443212925 frames, with delay: 1443312502, td_in_frames: 18706, rt: 6296250172, nt: 6296175548, current_delay: 1443219172, seqno: 20908, given timestamp: 2001208252. Aug 30 14:42:24 volumio-rpi shairport-sync[26528]: Very large sync error: 1443212934 frames, with delay: 1443312511, td_in_frames: 18716, rt: 6296250172, nt: 6296175900, current_delay: 1443219523, seqno: 20909, given timestamp: 2001208604. Aug 30 14:42:24 volumio-rpi shairport-sync[26528]: Very large sync error: 1443212929 frames, with delay: 1443312506, td_in_frames: 19197, rt: 6296250172, nt: 6296176252, current_delay: 1443219389, seqno: 20910, given timestamp: 2001208956. Aug 30 14:42:24 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1443212929 -- setting it to 132300. Aug 30 14:42:24 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:24 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:24 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:24 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:24 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:24 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:24 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:24 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:24 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:24 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:24 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:24 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:24 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:25 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:25 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:25 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:25 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:25 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:25 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:25 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:25 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:25 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:25 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:25 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:25 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:25 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:26 volumio-rpi sudo[30089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 14:42:26 volumio-rpi sudo[30089]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 14:42:26 volumio-rpi sudo[30089]: pam_unix(sudo:session): session closed for user root Aug 30 14:42:26 volumio-rpi sudo[30091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 14:42:26 volumio-rpi sudo[30091]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 14:42:26 volumio-rpi sudo[30091]: pam_unix(sudo:session): session closed for user root Aug 30 14:42:26 volumio-rpi volumio[25936]: verbose: New Socket.io Connection to 192.168.2.141 from 192.168.2.223 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Edg/139.0.0.0 Engine version: 3 Transport: polling Total Clients: 9 Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioGetState Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 14:42:26 volumio-rpi volumio[25936]: info: Received Get System Info Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 14:42:26 volumio-rpi volumio[25936]: info: Discovery: Getting this device information Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioGetState Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioGetState Aug 30 14:42:26 volumio-rpi volumio[25936]: info: Listing playlists Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 14:42:26 volumio-rpi volumio[25936]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%91%A8%E6%9D%B0%E4%BC%A6/%E4%B8%83%E9%87%8C%E9%A6%99/09078941-bede-47c6-bff1-a3153082e361.jpg' Aug 30 14:42:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 30 14:42:27 volumio-rpi shairport-sync[26528]: Very large sync error: 1443080451 frames, with delay: 1443180028, td_in_frames: 18197, rt: 6296383932, nt: 6296308956, current_delay: 1443086855, seqno: 21287, given timestamp: 2001341660. Aug 30 14:42:27 volumio-rpi shairport-sync[26528]: Very large sync error: 1443080463 frames, with delay: 1443180040, td_in_frames: 18650, rt: 6296383932, nt: 6296309308, current_delay: 1443086766, seqno: 21288, given timestamp: 2001342012. Aug 30 14:42:27 volumio-rpi shairport-sync[26528]: Very large sync error: 1443080476 frames, with delay: 1443180053, td_in_frames: 19148, rt: 6296383932, nt: 6296309660, current_delay: 1443086633, seqno: 21289, given timestamp: 2001342364. Aug 30 14:42:27 volumio-rpi shairport-sync[26528]: Very large sync error: 1443080469 frames, with delay: 1443180046, td_in_frames: 19186, rt: 6296383932, nt: 6296310012, current_delay: 1443086940, seqno: 21290, given timestamp: 2001342716. Aug 30 14:42:27 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1443080469 -- setting it to 132300. Aug 30 14:42:27 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:27 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:27 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:27 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:27 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:27 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:27 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:27 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:27 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:27 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:27 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:27 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:27 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:27 volumio-rpi volumio[25936]: info: MyVolumio login type: Token Aug 30 14:42:27 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioGetState Aug 30 14:42:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 30 14:42:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:28 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:28 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:28 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:28 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 14:42:28 volumio-rpi volumio[25936]: info: Received Get System Info Aug 30 14:42:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 14:42:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 14:42:28 volumio-rpi volumio[25936]: info: Discovery: Getting this device information Aug 30 14:42:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioGetState Aug 30 14:42:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 14:42:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:29 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:29 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:29 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:29 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:29 volumio-rpi wpa_supplicant[881]: wlan0: Failed to initiate sched scan Aug 30 14:42:30 volumio-rpi shairport-sync[26528]: Very large sync error: 1442948002 frames, with delay: 1443047579, td_in_frames: 18461, rt: 6296517340, nt: 6296442716, current_delay: 1442954494, seqno: 21667, given timestamp: 2001475420. Aug 30 14:42:30 volumio-rpi shairport-sync[26528]: Very large sync error: 1442947999 frames, with delay: 1443047576, td_in_frames: 18899, rt: 6296517340, nt: 6296443068, current_delay: 1442954405, seqno: 21668, given timestamp: 2001475772. Aug 30 14:42:30 volumio-rpi shairport-sync[26528]: Very large sync error: 1442948014 frames, with delay: 1443047591, td_in_frames: 19399, rt: 6296517340, nt: 6296443420, current_delay: 1442954272, seqno: 21669, given timestamp: 2001476124. Aug 30 14:42:30 volumio-rpi shairport-sync[26528]: Very large sync error: 1442948008 frames, with delay: 1443047585, td_in_frames: 19438, rt: 6296517340, nt: 6296443772, current_delay: 1442954579, seqno: 21670, given timestamp: 2001476476. Aug 30 14:42:30 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1442948008 -- setting it to 132300. Aug 30 14:42:30 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:30 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:30 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:30 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:30 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:30 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:30 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:30 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:30 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:30 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:30 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:30 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:30 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:31 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:31 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:31 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:31 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:31 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:31 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:31 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:31 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:31 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:31 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:31 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:31 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:31 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:32 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:32 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:32 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:32 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:33 volumio-rpi shairport-sync[26528]: Very large sync error: 1442815564 frames, with delay: 1442915141, td_in_frames: 18560, rt: 6296651100, nt: 6296576476, current_delay: 1442821957, seqno: 22047, given timestamp: 2001609180. Aug 30 14:42:33 volumio-rpi shairport-sync[26528]: Very large sync error: 1442815536 frames, with delay: 1442915113, td_in_frames: 19106, rt: 6296651100, nt: 6296576828, current_delay: 1442821735, seqno: 22048, given timestamp: 2001609532. Aug 30 14:42:33 volumio-rpi shairport-sync[26528]: Very large sync error: 1442815547 frames, with delay: 1442915124, td_in_frames: 19162, rt: 6296651100, nt: 6296577180, current_delay: 1442822042, seqno: 22049, given timestamp: 2001609884. Aug 30 14:42:33 volumio-rpi shairport-sync[26528]: Very large sync error: 1442815569 frames, with delay: 1442915146, td_in_frames: 19493, rt: 6296651100, nt: 6296577532, current_delay: 1442822085, seqno: 22050, given timestamp: 2001610236. Aug 30 14:42:33 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1442815569 -- setting it to 132300. Aug 30 14:42:33 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:33 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:33 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:33 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:33 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:33 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:33 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:33 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:33 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:33 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:33 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:33 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:33 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:34 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:34 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:34 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:34 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:34 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:34 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:34 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:34 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:34 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:34 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:34 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:34 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:34 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:35 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:35 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:35 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:35 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:35 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:35 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:35 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:35 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:35 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:35 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:35 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:35 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:35 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:35 volumio-rpi wpa_supplicant[881]: wlan0: Failed to initiate sched scan Aug 30 14:42:36 volumio-rpi shairport-sync[26528]: Very large sync error: 1442683100 frames, with delay: 1442782677, td_in_frames: 19074, rt: 6296784508, nt: 6296710236, current_delay: 1442689331, seqno: 22427, given timestamp: 2001742940. Aug 30 14:42:36 volumio-rpi shairport-sync[26528]: Very large sync error: 1442683081 frames, with delay: 1442782658, td_in_frames: 19100, rt: 6296784508, nt: 6296710588, current_delay: 1442689638, seqno: 22428, given timestamp: 2001743292. Aug 30 14:42:36 volumio-rpi shairport-sync[26528]: Very large sync error: 1442683120 frames, with delay: 1442782697, td_in_frames: 19580, rt: 6296784508, nt: 6296710940, current_delay: 1442689549, seqno: 22429, given timestamp: 2001743644. Aug 30 14:42:36 volumio-rpi shairport-sync[26528]: Very large sync error: 1442683088 frames, with delay: 1442782665, td_in_frames: 19989, rt: 6296784508, nt: 6296711292, current_delay: 1442689460, seqno: 22430, given timestamp: 2001743996. Aug 30 14:42:36 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1442683088 -- setting it to 132300. Aug 30 14:42:36 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:36 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:36 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:36 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:36 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:36 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:36 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:36 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:36 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:36 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:36 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:36 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:36 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:36 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 30 14:42:37 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:37 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:37 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:37 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:37 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:37 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:37 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:37 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:37 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:37 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:37 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:37 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:37 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:38 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:38 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:38 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:38 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:38 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:38 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:38 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:38 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:38 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:38 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:38 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:38 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:38 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:39 volumio-rpi shairport-sync[26528]: Very large sync error: 1442550647 frames, with delay: 1442650224, td_in_frames: 18806, rt: 6296918268, nt: 6296843996, current_delay: 1442557146, seqno: 22807, given timestamp: 2001876700. Aug 30 14:42:39 volumio-rpi shairport-sync[26528]: Very large sync error: 1442550652 frames, with delay: 1442650229, td_in_frames: 19252, rt: 6296918268, nt: 6296844348, current_delay: 1442557057, seqno: 22808, given timestamp: 2001877052. Aug 30 14:42:39 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:39 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:39 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:39 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:39 volumio-rpi shairport-sync[26528]: Very large sync error: 1442550622 frames, with delay: 1442650199, td_in_frames: 19531, rt: 6296918268, nt: 6296844700, current_delay: 1442557100, seqno: 22809, given timestamp: 2001877404. Aug 30 14:42:39 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:39 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:39 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:39 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:39 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:39 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:39 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:39 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:39 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:39 volumio-rpi shairport-sync[26528]: Very large sync error: 1442550662 frames, with delay: 1442650239, td_in_frames: 20144, rt: 6296918268, nt: 6296845052, current_delay: 1442556879, seqno: 22810, given timestamp: 2001877756. Aug 30 14:42:39 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1442550662 -- setting it to 132300. Aug 30 14:42:40 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:40 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:40 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:40 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:40 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:40 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:40 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:40 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:40 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:40 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:40 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:40 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:40 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:40 volumio-rpi wpa_supplicant[881]: wlan0: Failed to initiate sched scan Aug 30 14:42:41 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:41 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:41 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:41 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:41 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:41 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:41 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:41 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:41 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:41 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:41 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:41 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:41 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:42 volumio-rpi shairport-sync[26528]: Very large sync error: 1442418163 frames, with delay: 1442517740, td_in_frames: 18287, rt: 6297052732, nt: 6296977756, current_delay: 1442424477, seqno: 23187, given timestamp: 2002010460. Aug 30 14:42:42 volumio-rpi shairport-sync[26528]: Very large sync error: 1442418189 frames, with delay: 1442517766, td_in_frames: 18358, rt: 6297052732, nt: 6296978108, current_delay: 1442424784, seqno: 23188, given timestamp: 2002010812. Aug 30 14:42:42 volumio-rpi shairport-sync[26528]: Very large sync error: 1442418195 frames, with delay: 1442517772, td_in_frames: 18718, rt: 6297052732, nt: 6296978460, current_delay: 1442424782, seqno: 23189, given timestamp: 2002011164. Aug 30 14:42:42 volumio-rpi shairport-sync[26528]: Very large sync error: 1442418179 frames, with delay: 1442517756, td_in_frames: 19231, rt: 6297052732, nt: 6296978812, current_delay: 1442424605, seqno: 23190, given timestamp: 2002011516. Aug 30 14:42:42 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1442418179 -- setting it to 132300. Aug 30 14:42:42 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:42 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:42 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:42 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:42 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:42 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:42 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:42 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:42 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:42 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:42 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:42 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:42 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:43 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:43 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:43 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:43 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:43 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:43 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:43 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:43 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:43 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:43 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:43 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:43 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:43 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:44 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:44 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:44 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:44 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:44 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:44 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:44 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:44 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:44 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:44 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:44 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:44 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:44 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:45 volumio-rpi shairport-sync[26528]: Very large sync error: 1442285737 frames, with delay: 1442385314, td_in_frames: 18531, rt: 6297186140, nt: 6297111516, current_delay: 1442292159, seqno: 23567, given timestamp: 2002144220. Aug 30 14:42:45 volumio-rpi shairport-sync[26528]: Very large sync error: 1442285735 frames, with delay: 1442385312, td_in_frames: 18970, rt: 6297186140, nt: 6297111868, current_delay: 1442292070, seqno: 23568, given timestamp: 2002144572. Aug 30 14:42:45 volumio-rpi shairport-sync[26528]: Very large sync error: 1442285720 frames, with delay: 1442385297, td_in_frames: 19484, rt: 6297186140, nt: 6297112220, current_delay: 1442291893, seqno: 23569, given timestamp: 2002144924. Aug 30 14:42:45 volumio-rpi shairport-sync[26528]: Very large sync error: 1442285713 frames, with delay: 1442385290, td_in_frames: 19522, rt: 6297186140, nt: 6297112572, current_delay: 1442292200, seqno: 23570, given timestamp: 2002145276. Aug 30 14:42:45 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1442285713 -- setting it to 132300. Aug 30 14:42:45 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:45 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:45 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:45 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:45 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:45 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:45 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:45 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:45 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:45 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:45 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:45 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:45 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:46 volumio-rpi wpa_supplicant[881]: wlan0: Failed to initiate sched scan Aug 30 14:42:46 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:46 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:46 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:46 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:46 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:46 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:46 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:46 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:46 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:46 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:46 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:46 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:46 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:47 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:47 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:47 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:47 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:47 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:47 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:47 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:47 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:47 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:47 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:47 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:47 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:47 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:48 volumio-rpi shairport-sync[26528]: Very large sync error: 1442153283 frames, with delay: 1442252860, td_in_frames: 18614, rt: 6297319900, nt: 6297245276, current_delay: 1442159622, seqno: 23947, given timestamp: 2002277980. Aug 30 14:42:48 volumio-rpi shairport-sync[26528]: Very large sync error: 1442153284 frames, with delay: 1442252861, td_in_frames: 19144, rt: 6297319900, nt: 6297245628, current_delay: 1442159445, seqno: 23948, given timestamp: 2002278332. Aug 30 14:42:48 volumio-rpi shairport-sync[26528]: Very large sync error: 1442153265 frames, with delay: 1442252842, td_in_frames: 19170, rt: 6297319900, nt: 6297245980, current_delay: 1442159752, seqno: 23949, given timestamp: 2002278684. Aug 30 14:42:48 volumio-rpi shairport-sync[26528]: Very large sync error: 1442153282 frames, with delay: 1442252859, td_in_frames: 19540, rt: 6297319900, nt: 6297246332, current_delay: 1442159751, seqno: 23950, given timestamp: 2002279036. Aug 30 14:42:48 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1442153282 -- setting it to 132300. Aug 30 14:42:48 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:48 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:48 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:48 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:48 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:48 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:48 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:48 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:48 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:48 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:48 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:48 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:48 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:49 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:49 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:49 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:49 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:49 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:49 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:49 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:49 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:49 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:49 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:49 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:49 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:49 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:50 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:50 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:50 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:50 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:50 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:50 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:50 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:50 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:50 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:50 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:50 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:50 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:50 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:51 volumio-rpi shairport-sync[26528]: Very large sync error: 1442020843 frames, with delay: 1442120420, td_in_frames: 18095, rt: 6297454012, nt: 6297379036, current_delay: 1442027349, seqno: 24327, given timestamp: 2002411740. Aug 30 14:42:51 volumio-rpi shairport-sync[26528]: Very large sync error: 1442020842 frames, with delay: 1442120419, td_in_frames: 18624, rt: 6297454012, nt: 6297379388, current_delay: 1442027171, seqno: 24328, given timestamp: 2002412092. Aug 30 14:42:51 volumio-rpi shairport-sync[26528]: Very large sync error: 1442020818 frames, with delay: 1442120395, td_in_frames: 18997, rt: 6297454012, nt: 6297379740, current_delay: 1442027126, seqno: 24329, given timestamp: 2002412444. Aug 30 14:42:51 volumio-rpi shairport-sync[26528]: Very large sync error: 1442020830 frames, with delay: 1442120407, td_in_frames: 19054, rt: 6297454012, nt: 6297380092, current_delay: 1442027433, seqno: 24330, given timestamp: 2002412796. Aug 30 14:42:51 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1442020830 -- setting it to 132300. Aug 30 14:42:51 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:51 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:51 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:51 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:51 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:51 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:51 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:51 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:51 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:51 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:51 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:51 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:51 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:52 volumio-rpi wpa_supplicant[881]: wlan0: Failed to initiate sched scan Aug 30 14:42:52 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:52 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:52 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:52 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:52 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:52 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:52 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:52 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:52 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:52 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:52 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:52 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:52 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:53 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:53 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:53 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:53 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:53 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:53 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:53 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:53 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:53 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:53 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:53 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:53 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:53 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:54 volumio-rpi shairport-sync[26528]: Very large sync error: 1441888380 frames, with delay: 1441987957, td_in_frames: 18346, rt: 6297587420, nt: 6297512796, current_delay: 1441894987, seqno: 24707, given timestamp: 2002545500. Aug 30 14:42:54 volumio-rpi shairport-sync[26528]: Very large sync error: 1441888385 frames, with delay: 1441987962, td_in_frames: 18792, rt: 6297587420, nt: 6297513148, current_delay: 1441894898, seqno: 24708, given timestamp: 2002545852. Aug 30 14:42:54 volumio-rpi shairport-sync[26528]: Very large sync error: 1441888355 frames, with delay: 1441987932, td_in_frames: 19291, rt: 6297587420, nt: 6297513500, current_delay: 1441894721, seqno: 24709, given timestamp: 2002546204. Aug 30 14:42:54 volumio-rpi shairport-sync[26528]: Very large sync error: 1441888378 frames, with delay: 1441987955, td_in_frames: 19711, rt: 6297587420, nt: 6297513852, current_delay: 1441894676, seqno: 24710, given timestamp: 2002546556. Aug 30 14:42:54 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1441888378 -- setting it to 132300. Aug 30 14:42:54 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:54 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:54 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:54 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:54 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:54 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:54 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:54 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:54 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:54 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:54 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:54 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:54 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:55 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:55 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:55 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:55 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:55 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:55 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:55 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:55 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:55 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:55 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:55 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:55 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:55 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:56 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:56 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:56 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:56 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:56 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:56 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:56 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:56 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:56 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:56 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:56 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:56 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:56 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:57 volumio-rpi volumio[25936]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Aug 30 14:42:57 volumio-rpi volumio[25936]: info: Retrying Login Due to Network Error Aug 30 14:42:57 volumio-rpi shairport-sync[26528]: Very large sync error: 1441755907 frames, with delay: 1441855484, td_in_frames: 18454, rt: 6297721532, nt: 6297646556, current_delay: 1441762054, seqno: 25087, given timestamp: 2002679260. Aug 30 14:42:57 volumio-rpi shairport-sync[26528]: Very large sync error: 1441755926 frames, with delay: 1441855503, td_in_frames: 18518, rt: 6297721532, nt: 6297646908, current_delay: 1441762361, seqno: 25088, given timestamp: 2002679612. Aug 30 14:42:57 volumio-rpi shairport-sync[26528]: Very large sync error: 1441755919 frames, with delay: 1441855496, td_in_frames: 18908, rt: 6297721532, nt: 6297647260, current_delay: 1441762316, seqno: 25089, given timestamp: 2002679964. Aug 30 14:42:57 volumio-rpi shairport-sync[26528]: Very large sync error: 1441755897 frames, with delay: 1441855474, td_in_frames: 19416, rt: 6297721532, nt: 6297647612, current_delay: 1441762138, seqno: 25090, given timestamp: 2002680316. Aug 30 14:42:57 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1441755897 -- setting it to 132300. Aug 30 14:42:57 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:57 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:57 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:57 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:57 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:57 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:57 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:57 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:57 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:57 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:57 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:57 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:57 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:57 volumio-rpi wpa_supplicant[881]: wlan0: Failed to initiate sched scan Aug 30 14:42:58 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:58 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:58 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:58 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:58 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:58 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:58 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:58 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:58 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:58 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:58 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:58 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:58 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:59 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:59 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:59 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:42:59 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:59 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:59 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:59 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:59 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:59 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:59 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:42:59 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:42:59 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:42:59 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:00 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:00 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:00 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:00 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:00 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:00 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:00 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:00 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:00 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:00 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:00 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:00 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:00 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:00 volumio-rpi shairport-sync[26528]: Very large sync error: 1441623482 frames, with delay: 1441723059, td_in_frames: 18214, rt: 6297855292, nt: 6297780316, current_delay: 1441629869, seqno: 25467, given timestamp: 2002813020. Aug 30 14:43:00 volumio-rpi shairport-sync[26528]: Very large sync error: 1441623476 frames, with delay: 1441723053, td_in_frames: 18694, rt: 6297855292, nt: 6297780668, current_delay: 1441629735, seqno: 25468, given timestamp: 2002813372. Aug 30 14:43:00 volumio-rpi shairport-sync[26528]: Very large sync error: 1441623443 frames, with delay: 1441723020, td_in_frames: 18706, rt: 6297855292, nt: 6297781020, current_delay: 1441630042, seqno: 25469, given timestamp: 2002813724. Aug 30 14:43:00 volumio-rpi shairport-sync[26528]: Very large sync error: 1441623483 frames, with delay: 1441723060, td_in_frames: 19187, rt: 6297855292, nt: 6297781372, current_delay: 1441629953, seqno: 25470, given timestamp: 2002814076. Aug 30 14:43:00 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1441623483 -- setting it to 132300. Aug 30 14:43:01 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:01 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:01 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:01 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:01 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:01 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:01 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:01 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:01 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:01 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:01 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:01 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:01 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:02 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:02 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:02 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:02 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:02 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:02 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:02 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:02 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:02 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:02 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:02 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:02 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:02 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:03 volumio-rpi wpa_supplicant[881]: wlan0: Failed to initiate sched scan Aug 30 14:43:03 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:03 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:03 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:03 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:03 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:03 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:03 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:03 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:03 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:03 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:03 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:03 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:03 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:03 volumio-rpi shairport-sync[26528]: Very large sync error: 1441490999 frames, with delay: 1441590576, td_in_frames: 17829, rt: 6297989404, nt: 6297914076, current_delay: 1441497419, seqno: 25847, given timestamp: 2002946780. Aug 30 14:43:03 volumio-rpi shairport-sync[26528]: Very large sync error: 1441491027 frames, with delay: 1441590604, td_in_frames: 18386, rt: 6297989404, nt: 6297914428, current_delay: 1441497242, seqno: 25848, given timestamp: 2002947132. Aug 30 14:43:03 volumio-rpi shairport-sync[26528]: Very large sync error: 1441491006 frames, with delay: 1441590583, td_in_frames: 18498, rt: 6297989404, nt: 6297914780, current_delay: 1441497461, seqno: 25849, given timestamp: 2002947484. Aug 30 14:43:03 volumio-rpi shairport-sync[26528]: Very large sync error: 1441491015 frames, with delay: 1441590592, td_in_frames: 18728, rt: 6297989404, nt: 6297915132, current_delay: 1441497592, seqno: 25850, given timestamp: 2002947836. Aug 30 14:43:03 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1441491015 -- setting it to 132300. Aug 30 14:43:04 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:04 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:04 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:04 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:04 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:04 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:04 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:04 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:04 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:04 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:04 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:04 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:04 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:05 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:05 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:05 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:05 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:05 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:05 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:05 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:05 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:05 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:05 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:05 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:05 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:05 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:06 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:06 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:06 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:06 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:06 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:06 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:06 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:06 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:06 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:06 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:06 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:06 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:06 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:06 volumio-rpi shairport-sync[26528]: Very large sync error: 1441358558 frames, with delay: 1441458135, td_in_frames: 18057, rt: 6298122812, nt: 6298047836, current_delay: 1441365102, seqno: 26227, given timestamp: 2003080540. Aug 30 14:43:06 volumio-rpi shairport-sync[26528]: Very large sync error: 1441358549 frames, with delay: 1441458126, td_in_frames: 18621, rt: 6298122812, nt: 6298048188, current_delay: 1441364881, seqno: 26228, given timestamp: 2003080892. Aug 30 14:43:06 volumio-rpi shairport-sync[26528]: Very large sync error: 1441358574 frames, with delay: 1441458151, td_in_frames: 18955, rt: 6298122812, nt: 6298048540, current_delay: 1441364924, seqno: 26229, given timestamp: 2003081244. Aug 30 14:43:06 volumio-rpi shairport-sync[26528]: Very large sync error: 1441358552 frames, with delay: 1441458129, td_in_frames: 19463, rt: 6298122812, nt: 6298048892, current_delay: 1441364746, seqno: 26230, given timestamp: 2003081596. Aug 30 14:43:06 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1441358552 -- setting it to 132300. Aug 30 14:43:07 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:07 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:07 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:07 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:07 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:07 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:07 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:07 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:07 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:07 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:07 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:07 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:07 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:07 volumio-rpi volumio[25936]: info: MyVolumio login type: Token Aug 30 14:43:08 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:08 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:08 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:08 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:08 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:08 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:08 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:08 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:08 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:08 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:08 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:08 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:08 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:08 volumio-rpi wpa_supplicant[881]: wlan0: Failed to initiate sched scan Aug 30 14:43:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:09 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:09 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:09 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:09 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:09 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:09 volumio-rpi shairport-sync[26528]: Very large sync error: 1441226085 frames, with delay: 1441325662, td_in_frames: 17813, rt: 6298256924, nt: 6298181596, current_delay: 1441232521, seqno: 26607, given timestamp: 2003214300. Aug 30 14:43:09 volumio-rpi shairport-sync[26528]: Very large sync error: 1441226111 frames, with delay: 1441325688, td_in_frames: 18501, rt: 6298256924, nt: 6298181948, current_delay: 1441232211, seqno: 26608, given timestamp: 2003214652. Aug 30 14:43:09 volumio-rpi shairport-sync[26528]: Very large sync error: 1441226082 frames, with delay: 1441325659, td_in_frames: 18605, rt: 6298256924, nt: 6298182300, current_delay: 1441232430, seqno: 26609, given timestamp: 2003215004. Aug 30 14:43:09 volumio-rpi shairport-sync[26528]: Very large sync error: 1441226099 frames, with delay: 1441325676, td_in_frames: 18711, rt: 6298256924, nt: 6298182652, current_delay: 1441232693, seqno: 26610, given timestamp: 2003215356. Aug 30 14:43:09 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1441226099 -- setting it to 132300. Aug 30 14:43:10 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:10 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:10 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:10 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:10 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:10 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:10 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:10 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:10 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:10 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:10 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:10 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:10 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:11 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:11 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:11 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:11 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:11 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:11 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:11 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:11 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:11 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:11 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:11 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:11 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:11 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:12 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:12 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:12 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:12 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:12 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:12 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:12 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:12 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:12 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:12 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:12 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:12 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:12 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:12 volumio-rpi shairport-sync[26528]: Very large sync error: 1441093652 frames, with delay: 1441193229, td_in_frames: 17874, rt: 6298390684, nt: 6298315356, current_delay: 1441100027, seqno: 26987, given timestamp: 2003348060. Aug 30 14:43:12 volumio-rpi shairport-sync[26528]: Very large sync error: 1441093645 frames, with delay: 1441193222, td_in_frames: 18396, rt: 6298390684, nt: 6298315708, current_delay: 1441099850, seqno: 26988, given timestamp: 2003348412. Aug 30 14:43:12 volumio-rpi shairport-sync[26528]: Very large sync error: 1441093425 frames, with delay: 1441193002, td_in_frames: 18442, rt: 6298390684, nt: 6298316060, current_delay: 1441099936, seqno: 26989, given timestamp: 2003348764. Aug 30 14:43:12 volumio-rpi shairport-sync[26528]: Very large sync error: 1441093439 frames, with delay: 1441193016, td_in_frames: 18897, rt: 6298390684, nt: 6298316412, current_delay: 1441099847, seqno: 26990, given timestamp: 2003349116. Aug 30 14:43:12 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1441093439 -- setting it to 132300. Aug 30 14:43:13 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:13 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:13 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:13 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:13 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:13 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:13 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:13 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:13 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:13 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:13 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:13 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:13 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:14 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:14 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:14 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:14 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:14 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:14 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:14 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:14 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:14 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:14 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:14 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:14 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:14 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:14 volumio-rpi wpa_supplicant[881]: wlan0: Failed to initiate sched scan Aug 30 14:43:15 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:15 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:15 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:15 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:15 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:15 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:15 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:15 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:15 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:15 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:15 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:15 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:15 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:15 volumio-rpi shairport-sync[26528]: Very large sync error: 1440960960 frames, with delay: 1441060537, td_in_frames: 17544, rt: 6298524796, nt: 6298449116, current_delay: 1440967313, seqno: 27367, given timestamp: 2003481820. Aug 30 14:43:15 volumio-rpi shairport-sync[26528]: Very large sync error: 1440960964 frames, with delay: 1441060541, td_in_frames: 18077, rt: 6298524796, nt: 6298449468, current_delay: 1440967136, seqno: 27368, given timestamp: 2003482172. Aug 30 14:43:15 volumio-rpi shairport-sync[26528]: Very large sync error: 1440960987 frames, with delay: 1441060564, td_in_frames: 18101, rt: 6298524796, nt: 6298449820, current_delay: 1440967487, seqno: 27369, given timestamp: 2003482524. Aug 30 14:43:15 volumio-rpi shairport-sync[26528]: Very large sync error: 1440960966 frames, with delay: 1441060543, td_in_frames: 18477, rt: 6298524796, nt: 6298450172, current_delay: 1440967442, seqno: 27370, given timestamp: 2003482876. Aug 30 14:43:15 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1440960966 -- setting it to 132300. Aug 30 14:43:16 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:16 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:16 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:16 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:16 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:16 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:16 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:16 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:16 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:16 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:16 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:16 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:16 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:17 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:17 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:17 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:17 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:17 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:17 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:17 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:17 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:17 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:17 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:17 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:17 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:17 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:18 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:18 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:18 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:18 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:18 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:18 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:18 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:18 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:18 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:18 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:18 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:18 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:18 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:18 volumio-rpi shairport-sync[26528]: Very large sync error: 1440828520 frames, with delay: 1440928097, td_in_frames: 18038, rt: 6298658204, nt: 6298582876, current_delay: 1440834731, seqno: 27747, given timestamp: 2003615580. Aug 30 14:43:18 volumio-rpi shairport-sync[26528]: Very large sync error: 1440828515 frames, with delay: 1440928092, td_in_frames: 18078, rt: 6298658204, nt: 6298583228, current_delay: 1440835038, seqno: 27748, given timestamp: 2003615932. Aug 30 14:43:18 volumio-rpi shairport-sync[26528]: Very large sync error: 1440828512 frames, with delay: 1440928089, td_in_frames: 18560, rt: 6298658204, nt: 6298583580, current_delay: 1440834905, seqno: 27749, given timestamp: 2003616284. Aug 30 14:43:18 volumio-rpi shairport-sync[26528]: Very large sync error: 1440828511 frames, with delay: 1440928088, td_in_frames: 18956, rt: 6298658204, nt: 6298583932, current_delay: 1440834860, seqno: 27750, given timestamp: 2003616636. Aug 30 14:43:18 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1440828511 -- setting it to 132300. Aug 30 14:43:19 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:19 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:19 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:19 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:19 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:19 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:19 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:19 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:19 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:19 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:19 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:19 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:19 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:20 volumio-rpi wpa_supplicant[881]: wlan0: Failed to initiate sched scan Aug 30 14:43:20 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:20 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:20 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:20 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:20 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:20 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:20 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:20 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:20 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:20 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:20 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:20 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:20 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:21 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:21 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:21 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:21 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:21 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:21 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:21 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:21 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:21 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:21 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:21 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:21 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:21 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:21 volumio-rpi shairport-sync[26528]: Very large sync error: 1440696082 frames, with delay: 1440795659, td_in_frames: 18357, rt: 6298791612, nt: 6298716636, current_delay: 1440702326, seqno: 28127, given timestamp: 2003749340. Aug 30 14:43:21 volumio-rpi shairport-sync[26528]: Very large sync error: 1440696063 frames, with delay: 1440795640, td_in_frames: 18383, rt: 6298791612, nt: 6298716988, current_delay: 1440702633, seqno: 28128, given timestamp: 2003749692. Aug 30 14:43:21 volumio-rpi shairport-sync[26528]: Very large sync error: 1440696076 frames, with delay: 1440795653, td_in_frames: 18793, rt: 6298791612, nt: 6298717340, current_delay: 1440702588, seqno: 28129, given timestamp: 2003750044. Aug 30 14:43:21 volumio-rpi shairport-sync[26528]: Very large sync error: 1440696059 frames, with delay: 1440795636, td_in_frames: 19305, rt: 6298791612, nt: 6298717692, current_delay: 1440702411, seqno: 28130, given timestamp: 2003750396. Aug 30 14:43:21 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1440696059 -- setting it to 132300. Aug 30 14:43:22 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:22 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:22 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:22 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:22 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:22 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:22 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:22 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:22 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:22 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:22 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:22 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:22 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:23 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:23 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:23 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:23 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:23 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:23 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:23 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:23 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:23 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:23 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:23 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:23 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:23 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:24 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:24 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:24 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:24 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:24 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:24 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:24 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:24 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:24 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:24 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:24 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:24 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:24 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:24 volumio-rpi shairport-sync[26528]: Very large sync error: 1440563616 frames, with delay: 1440663193, td_in_frames: 17856, rt: 6298925724, nt: 6298850396, current_delay: 1440570009, seqno: 28507, given timestamp: 2003883100. Aug 30 14:43:24 volumio-rpi shairport-sync[26528]: Very large sync error: 1440563605 frames, with delay: 1440663182, td_in_frames: 18374, rt: 6298925724, nt: 6298850748, current_delay: 1440569832, seqno: 28508, given timestamp: 2003883452. Aug 30 14:43:24 volumio-rpi shairport-sync[26528]: Very large sync error: 1440563613 frames, with delay: 1440663190, td_in_frames: 18383, rt: 6298925724, nt: 6298851100, current_delay: 1440570183, seqno: 28509, given timestamp: 2003883804. Aug 30 14:43:24 volumio-rpi shairport-sync[26528]: Very large sync error: 1440563591 frames, with delay: 1440663168, td_in_frames: 18802, rt: 6298925724, nt: 6298851452, current_delay: 1440570094, seqno: 28510, given timestamp: 2003884156. Aug 30 14:43:24 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1440563591 -- setting it to 132300. Aug 30 14:43:25 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:25 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:25 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:25 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:25 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:25 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:25 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:25 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:25 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:25 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:25 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:25 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:25 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:25 volumio-rpi wpa_supplicant[881]: wlan0: Failed to initiate sched scan Aug 30 14:43:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:26 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:26 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:26 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:26 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:26 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:27 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:27 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:27 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:27 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:27 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:27 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:27 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:27 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:27 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:27 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:27 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:27 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:27 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:27 volumio-rpi shairport-sync[26528]: Very large sync error: 1440431159 frames, with delay: 1440530736, td_in_frames: 17804, rt: 6299059484, nt: 6298984156, current_delay: 1440437604, seqno: 28887, given timestamp: 2004016860. Aug 30 14:43:27 volumio-rpi shairport-sync[26528]: Very large sync error: 1440431142 frames, with delay: 1440530719, td_in_frames: 18405, rt: 6299059484, nt: 6298984508, current_delay: 1440437338, seqno: 28888, given timestamp: 2004017212. Aug 30 14:43:27 volumio-rpi shairport-sync[26528]: Very large sync error: 1440431153 frames, with delay: 1440530730, td_in_frames: 18461, rt: 6299059484, nt: 6298984860, current_delay: 1440437645, seqno: 28889, given timestamp: 2004017564. Aug 30 14:43:27 volumio-rpi shairport-sync[26528]: Very large sync error: 1440431137 frames, with delay: 1440530714, td_in_frames: 18798, rt: 6299059484, nt: 6298985212, current_delay: 1440437644, seqno: 28890, given timestamp: 2004017916. Aug 30 14:43:27 volumio-rpi shairport-sync[26528]: Shome mhistake shurely: very large number of frames to drop: 1440431137 -- setting it to 132300. Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:28 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:29 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:29 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:29 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:29 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:29 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:29 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:29 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:29 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:29 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:31 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:31 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:31 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:31 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:31 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:31 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:31 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:31 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:31 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:31 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:31 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:31 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:31 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:31 volumio-rpi wpa_supplicant[881]: wlan0: Failed to initiate sched scan Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:32 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:34 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:34 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:34 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:34 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:34 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:34 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:34 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:34 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:34 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:34 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:34 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:34 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:34 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:35 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:35 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:35 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:35 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:35 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:35 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:35 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:35 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:35 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:35 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:35 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:35 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:35 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:36 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:36 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:36 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:36 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:36 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:36 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:36 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:36 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:36 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:36 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:36 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:36 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:36 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:36 volumio-rpi wpa_supplicant[881]: wlan0: Failed to initiate sched scan Aug 30 14:43:37 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:37 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:37 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:37 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:37 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:37 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:37 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:37 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:37 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:37 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:37 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:37 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:37 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:37 volumio-rpi volumio[25936]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Aug 30 14:43:37 volumio-rpi volumio[25936]: info: Retrying Login Due to Network Error Aug 30 14:43:38 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:38 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:38 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:38 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:38 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:38 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:38 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:38 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:38 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:38 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:38 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:38 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:38 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:39 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:39 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:39 volumio-rpi volumio[25936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 14:43:39 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:39 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:39 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:39 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:39 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:39 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:39 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:39 volumio-rpi volumio[25936]: info: CoreCommandRouter::servicePushState Aug 30 14:43:39 volumio-rpi volumio[25936]: info: CoreStateMachine::pushState Aug 30 14:43:39 volumio-rpi volumio[25936]: info: CoreCommandRouter::volumioPushState Aug 30 14:43:42 volumio-rpi wpa_supplicant[881]: wlan0: Failed to initiate sched scan Aug 30 14:43:47 volumio-rpi volumio[25936]: info: MyVolumio login type: Token Aug 30 14:43:47 volumio-rpi volumio[25936]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 14:43:47 volumio-rpi volumio[25936]: Error: connect ETIMEDOUT 43.226.16.8:443 Aug 30 14:43:47 volumio-rpi volumio[25936]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Aug 30 14:43:47 volumio-rpi volumio[25936]: errno: -110, Aug 30 14:43:47 volumio-rpi volumio[25936]: code: 'ETIMEDOUT', Aug 30 14:43:47 volumio-rpi volumio[25936]: syscall: 'connect', Aug 30 14:43:47 volumio-rpi volumio[25936]: address: '43.226.16.8', Aug 30 14:43:47 volumio-rpi volumio[25936]: port: 443 Aug 30 14:43:47 volumio-rpi volumio[25936]: } Aug 30 14:43:47 volumio-rpi volumio[25936]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 14:43:48 volumio-rpi wpa_supplicant[881]: wlan0: Failed to initiate sched scan Aug 30 14:43:49 volumio-rpi sudo[31504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-30 14:42 Aug 30 14:43:49 volumio-rpi sudo[31504]: 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="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"