-- Logs begin at Sat 2024-10-05 09:39:21 CST, end at Sat 2024-10-05 09:41:44 CST. -- Oct 05 09:40:00 volumio3ontb16 sudo[2031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 09:40:00 volumio3ontb16 sudo[2031]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:40:00 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 05 09:40:00 volumio3ontb16 sudo[2031]: pam_unix(sudo:session): session closed for user root Oct 05 09:40:00 volumio3ontb16 sudo[2035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 09:40:00 volumio3ontb16 sudo[2035]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:40:00 volumio3ontb16 sudo[2035]: pam_unix(sudo:session): session closed for user root Oct 05 09:40:00 volumio3ontb16 sudo[2039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 05 09:40:00 volumio3ontb16 sudo[2039]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:40:01 volumio3ontb16 systemd[1]: Started UPnP Renderer front-end to MPD. Oct 05 09:40:01 volumio3ontb16 sudo[2039]: pam_unix(sudo:session): session closed for user root Oct 05 09:40:01 volumio3ontb16 volumio[1237]: info: Upmpdcli Daemon Started Oct 05 09:40:01 volumio3ontb16 volumio[2041]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 05 09:40:01 volumio3ontb16 volumio[1237]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 05 09:40:02 volumio3ontb16 sudo[2058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=guest,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.2.83/Storage\MUSIC /mnt/NAS/MyPassport Oct 05 09:40:02 volumio3ontb16 sudo[2058]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:40:02 volumio3ontb16 kernel: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Oct 05 09:40:02 volumio3ontb16 kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Oct 05 09:40:02 volumio3ontb16 kernel: CIFS: Attempting to mount //192.168.2.83/Storage/MUSIC Oct 05 09:40:02 volumio3ontb16 volumio[2041]: ..............................................++++ Oct 05 09:40:02 volumio3ontb16 volumio[2041]: ....................++++ Oct 05 09:40:02 volumio3ontb16 volumio[2041]: e is 65537 (0x010001) Oct 05 09:40:02 volumio3ontb16 volumio[2041]: writing RSA key Oct 05 09:40:03 volumio3ontb16 volumio-remote-updater[899]: [2024-10-05 09:40:03] [connect] Successful connection Oct 05 09:40:03 volumio3ontb16 volumio-remote-updater[899]: [2024-10-05 09:40:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728092403 101 Oct 05 09:40:03 volumio3ontb16 volumio[1237]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 9 Oct 05 09:40:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioGetState Oct 05 09:40:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: Adding plugin bluetooth to MyMusic Plugins Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: Adding plugin multiroom to MyMusic Plugins Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: Adding plugin metavolumio to MyMusic Plugins Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: Adding plugin cd_controller to MyMusic Plugins Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 05 09:40:06 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 05 09:40:06 volumio3ontb16 su[2077]: (to root) volumio on pts/0 Oct 05 09:40:06 volumio3ontb16 su[2077]: pam_unix(su:session): session opened for user root by volumio(uid=1000) Oct 05 09:40:07 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 05 09:40:07 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 05 09:40:07 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:40:07 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:40:07 volumio3ontb16 volumio[1237]: info: Starting MyVolumio Remote Streaming Endpoints Oct 05 09:40:07 volumio3ontb16 volumio[1237]: info: MyVolumio not started Oct 05 09:40:07 volumio3ontb16 volumio[1237]: info: Initializing device activation check Oct 05 09:40:07 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:40:07 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 05 09:40:07 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 05 09:40:08 volumio3ontb16 sudo[2058]: pam_unix(sudo:session): session closed for user root Oct 05 09:40:08 volumio3ontb16 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 05 09:40:08 volumio3ontb16 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 05 09:40:08 volumio3ontb16 volumio[1237]: info: Starting Streaming Service Transparent Proxy Oct 05 09:40:08 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 05 09:40:08 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 05 09:40:08 volumio3ontb16 volumio[1237]: info: Streaming services startup Oct 05 09:40:08 volumio3ontb16 volumio[1237]: info: Starting Streaming Daemon Oct 05 09:40:08 volumio3ontb16 sudo[2092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 05 09:40:08 volumio3ontb16 sudo[2092]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:40:08 volumio3ontb16 sudo[2092]: pam_unix(sudo:session): session closed for user root Oct 05 09:40:08 volumio3ontb16 volumio[1237]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 05 09:40:08 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 05 09:40:08 volumio3ontb16 volumio[1237]: error: Cannot start Volumio Streaming Daemon Oct 05 09:40:08 volumio3ontb16 volumio[1237]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 05 09:40:08 volumio3ontb16 volumio[1237]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 05 09:40:08 volumio3ontb16 volumio[1237]: info: Cannot mount NAS MyPassport at system boot, trial number 2 ,retrying in 5 seconds Oct 05 09:40:09 volumio3ontb16 volumio[1237]: STREAMING PROXY: Starting server on port 3245 Oct 05 09:40:09 volumio3ontb16 volumio[1237]: Node JS runtime: 14 Oct 05 09:40:10 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioGetState Oct 05 09:40:10 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:40:13 volumio3ontb16 sudo[2105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=guest,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.2.83/Storage\MUSIC /mnt/NAS/MyPassport Oct 05 09:40:13 volumio3ontb16 sudo[2105]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:40:13 volumio3ontb16 kernel: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Oct 05 09:40:13 volumio3ontb16 kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Oct 05 09:40:13 volumio3ontb16 kernel: CIFS: Attempting to mount //192.168.2.83/Storage/MUSIC Oct 05 09:40:17 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioGetState Oct 05 09:40:17 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:40:19 volumio3ontb16 sudo[2105]: pam_unix(sudo:session): session closed for user root Oct 05 09:40:19 volumio3ontb16 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 05 09:40:19 volumio3ontb16 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 05 09:40:19 volumio3ontb16 volumio[1237]: info: Cannot mount NAS MyPassport at system boot, trial number 3 ,retrying in 5 seconds Oct 05 09:40:22 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 05 09:40:22 volumio3ontb16 volumio[1237]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Oct 05 09:40:22 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioGetState Oct 05 09:40:22 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:40:24 volumio3ontb16 volumio[1237]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Oct 05 09:40:24 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:40:24 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 09:40:24 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:40:24 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 05 09:40:24 volumio3ontb16 volumio-remote-updater[899]: No test mode Oct 05 09:40:24 volumio3ontb16 volumio-remote-updater[899]: No alpha test mode Oct 05 09:40:24 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 05 09:40:24 volumio3ontb16 sudo[2167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=guest,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.2.83/Storage\MUSIC /mnt/NAS/MyPassport Oct 05 09:40:24 volumio3ontb16 sudo[2167]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:40:24 volumio3ontb16 kernel: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Oct 05 09:40:24 volumio3ontb16 kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Oct 05 09:40:24 volumio3ontb16 kernel: CIFS: Attempting to mount //192.168.2.83/Storage/MUSIC Oct 05 09:40:25 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioGetState Oct 05 09:40:25 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:40:26 volumio3ontb16 volumio[1237]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 05 09:40:26 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 05 09:40:27 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:40:27 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:40:27 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 05 09:40:27 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 05 09:40:27 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 05 09:40:27 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioGetBrowseSources Oct 05 09:40:27 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 09:40:28 volumio3ontb16 volumio[1237]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 05 09:40:28 volumio3ontb16 volumio[1237]: info: Completed starting MyVolumio Plugin Oct 05 09:40:28 volumio3ontb16 volumio[1237]: info: BOOT COMPLETED Oct 05 09:40:28 volumio3ontb16 volumio[1237]: [Metrics] CommandRouter: 41s 243.08ms Oct 05 09:40:28 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumiosetStartupVolume Oct 05 09:40:28 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 09:40:28 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 09:40:28 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::Close All Modals sent Oct 05 09:40:28 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::Close All Modals sent Oct 05 09:40:29 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioGetState Oct 05 09:40:29 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:40:29 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 05 09:40:29 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 05 09:40:29 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 05 09:40:31 volumio3ontb16 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 05 09:40:31 volumio3ontb16 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 05 09:40:31 volumio3ontb16 sudo[2167]: pam_unix(sudo:session): session closed for user root Oct 05 09:40:31 volumio3ontb16 volumio[1237]: info: Cannot mount NAS MyPassport at system boot, trial number 4 ,retrying in 5 seconds Oct 05 09:40:36 volumio3ontb16 sudo[2187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=guest,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.2.83/Storage\MUSIC /mnt/NAS/MyPassport Oct 05 09:40:36 volumio3ontb16 sudo[2187]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 09:40:36 volumio3ontb16 kernel: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Oct 05 09:40:36 volumio3ontb16 kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Oct 05 09:40:36 volumio3ontb16 kernel: CIFS: Attempting to mount //192.168.2.83/Storage/MUSIC Oct 05 09:40:40 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioGetState Oct 05 09:40:40 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:40:41 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioGetState Oct 05 09:40:41 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:40:42 volumio3ontb16 sudo[2187]: pam_unix(sudo:session): session closed for user root Oct 05 09:40:42 volumio3ontb16 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 05 09:40:42 volumio3ontb16 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 05 09:40:42 volumio3ontb16 volumio[1237]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 05 09:40:53 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioGetState Oct 05 09:40:53 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:40:55 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioGetState Oct 05 09:40:55 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPlay Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::play index undefined Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::startPlaybackTimer Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::clearAddPlayTracks NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/01 - Anon_ Dinaresade.ape Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand stop Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: sendMpdCommand stop took 10 milliseconds Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand clear Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: sendMpdCommand clear took 2 milliseconds Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand add "NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/01 - Anon_ Dinaresade.ape" Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:05 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces system playlist update Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Ignoring MPD Status Update Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:05 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces system playlist update Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Ignoring MPD Status Update Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: sendMpdCommand add "NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/01 - Anon_ Dinaresade.ape" took 10 milliseconds Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand play Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:05 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces system playlist update Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Ignoring MPD Status Update Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:05 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces system playlist update Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Ignoring MPD Status Update Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:05 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces system playlist update Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Ignoring MPD Status Update Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ------------------------------ 26ms Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ------------------------------ 23ms Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: sendMpdCommand play took 16 milliseconds Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ------------------------------ 14ms Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ------------------------------ 12ms Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ------------------------------ 9ms Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:05 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces system playlist update Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Ignoring MPD Status Update Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:05 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces system playlist update Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Ignoring MPD Status Update Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:05 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces system playlist update Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Ignoring MPD Status Update Oct 05 09:41:05 volumio3ontb16 volumio[1237]: error: Upnp client error: Error: This socket has been ended by the other party Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ------------------------------ 9ms Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ------------------------------ 8ms Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ------------------------------ 6ms Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioGetState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:05 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces state update: player Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ControllerMpd::getState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:05 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces state update: player Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ControllerMpd::getState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:05 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces state update: player Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ControllerMpd::getState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:05 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces state update: player Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ControllerMpd::getState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: sendMpdCommand status took 36 milliseconds Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:05 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces state update: player Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ControllerMpd::getState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: sendMpdCommand status took 37 milliseconds Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: sendMpdCommand status took 34 milliseconds Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: sendMpdCommand status took 27 milliseconds Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseTrackInfo Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ControllerMpd::pushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::servicePushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":833,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Anon_ Dinaresade.ape","artist":null,"album":null,"uri":"NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/01 - Anon_ Dinaresade.ape","trackType":"ape"} Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: CURRENT POSITION 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState stateService play Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState currentStatus stop Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ------------------------------ 58ms Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:05 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces state update: player Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ControllerMpd::getState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:05 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces state update: player Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ControllerMpd::getState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:05 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces state update: player Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ControllerMpd::getState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: sendMpdCommand status took 22 milliseconds Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: sendMpdCommand playlistinfo took 19 milliseconds Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: sendMpdCommand status took 10 milliseconds Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: sendMpdCommand status took 5 milliseconds Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: sendMpdCommand status took 3 milliseconds Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseTrackInfo Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseTrackInfo Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseTrackInfo Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ControllerMpd::pushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::servicePushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":833,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Anon_ Dinaresade.ape","artist":null,"album":null,"uri":"NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/01 - Anon_ Dinaresade.ape","trackType":"ape"} Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: CURRENT POSITION 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState stateService play Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState currentStatus play Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Received an update from plugin. extracting info from payload Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ControllerMpd::pushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::servicePushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: STATE SERVICE {"status":"play","position":0,"seek":113,"duration":833,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Anon_ Dinaresade.ape","artist":null,"album":null,"uri":"NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/01 - Anon_ Dinaresade.ape","trackType":"ape"} Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: CURRENT POSITION 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState stateService play Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState currentStatus play Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Received an update from plugin. extracting info from payload Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ControllerMpd::pushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::servicePushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: STATE SERVICE {"status":"play","position":0,"seek":523,"duration":833,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Anon_ Dinaresade.ape","artist":null,"album":null,"uri":"NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/01 - Anon_ Dinaresade.ape","trackType":"ape"} Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: CURRENT POSITION 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState stateService play Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState currentStatus play Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Received an update from plugin. extracting info from payload Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ------------------------------ 132ms Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ------------------------------ 129ms Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ------------------------------ 125ms Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: sendMpdCommand playlistinfo took 72 milliseconds Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: sendMpdCommand playlistinfo took 69 milliseconds Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: sendMpdCommand playlistinfo took 69 milliseconds Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: sendMpdCommand playlistinfo took 69 milliseconds Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseTrackInfo Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseTrackInfo Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseTrackInfo Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseTrackInfo Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ControllerMpd::pushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::servicePushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":833,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Anon_ Dinaresade.ape","artist":null,"album":null,"uri":"NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/01 - Anon_ Dinaresade.ape","trackType":"ape"} Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: CURRENT POSITION 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState stateService play Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState currentStatus play Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Received an update from plugin. extracting info from payload Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ControllerMpd::pushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::servicePushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":833,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Anon_ Dinaresade.ape","artist":null,"album":null,"uri":"NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/01 - Anon_ Dinaresade.ape","trackType":"ape"} Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: CURRENT POSITION 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState stateService play Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState currentStatus play Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Received an update from plugin. extracting info from payload Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ControllerMpd::pushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::servicePushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":833,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Anon_ Dinaresade.ape","artist":null,"album":null,"uri":"NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/01 - Anon_ Dinaresade.ape","trackType":"ape"} Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: CURRENT POSITION 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState stateService play Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState currentStatus play Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Received an update from plugin. extracting info from payload Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: ControllerMpd::pushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::servicePushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":833,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Anon_ Dinaresade.ape","artist":null,"album":null,"uri":"NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/01 - Anon_ Dinaresade.ape","trackType":"ape"} Oct 05 09:41:05 volumio3ontb16 volumio[1237]: verbose: CURRENT POSITION 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState stateService play Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState currentStatus play Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: Received an update from plugin. extracting info from payload Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:05 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:06 volumio3ontb16 volumio[1237]: info: ------------------------------ 194ms Oct 05 09:41:06 volumio3ontb16 volumio[1237]: info: ------------------------------ 180ms Oct 05 09:41:06 volumio3ontb16 volumio[1237]: info: ------------------------------ 177ms Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioNext Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::next Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::stop Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::stPlaybackTimer Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::updateTrackBlock Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrackBlock Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::serviceStop Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 0 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::serviceStop Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ControllerMpd::stop Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand stop Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:09 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces state update: player Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand stop took 37 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ControllerMpd::getState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::play index undefined Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::startPlaybackTimer Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::clearAddPlayTracks NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/02 - Bolero (From 'Carmen Suite').ape Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand stop Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::updateTrackBlock Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrackBlock Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand status took 5 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand stop took 2 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand clear Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:09 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces state update: player Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ControllerMpd::getState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:09 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces state update: player Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ControllerMpd::getState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:09 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces state update: player Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ControllerMpd::getState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:09 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces system playlist update Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Ignoring MPD Status Update Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand clear took 6 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand status took 4 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand status took 3 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseTrackInfo Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand add "NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/02 - Bolero (From 'Carmen Suite').ape" Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ControllerMpd::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::servicePushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Anon_ Dinaresade.ape","artist":null,"album":null,"uri":"NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/01 - Anon_ Dinaresade.ape","trackType":"ape"} Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: CURRENT POSITION 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState stateService stop Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState currentStatus stop Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: No code Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ControllerMpd::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::servicePushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: CURRENT POSITION 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState stateService stop Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState currentStatus stop Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: No code Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ControllerMpd::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::servicePushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: CURRENT POSITION 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState stateService stop Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState currentStatus stop Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: No code Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ------------------------------ 80ms Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ------------------------------ 68ms Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ------------------------------ 72ms Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:09 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces system playlist update Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Ignoring MPD Status Update Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:09 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces system playlist update Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Ignoring MPD Status Update Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:09 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces system playlist update Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Ignoring MPD Status Update Oct 05 09:41:09 volumio3ontb16 volumio[1237]: error: updateQueue error: null Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:09 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces system playlist update Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Ignoring MPD Status Update Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand status took 91 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ------------------------------ 91ms Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand add "NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/02 - Bolero (From 'Carmen Suite').ape" took 89 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ------------------------------ 19ms Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ------------------------------ 18ms Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ------------------------------ 18ms Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand play Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ControllerMpd::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::servicePushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: CURRENT POSITION 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState stateService stop Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState currentStatus stop Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: No code Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ------------------------------ 124ms Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:09 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces system playlist update Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Ignoring MPD Status Update Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:09 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces system playlist update Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Ignoring MPD Status Update Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:09 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces system playlist update Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Ignoring MPD Status Update Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ------------------------------ 56ms Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand play took 50 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ------------------------------ 21ms Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ------------------------------ 20ms Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ------------------------------ 19ms Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:09 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces state update: player Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ControllerMpd::getState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:09 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces state update: player Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ControllerMpd::getState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:09 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces state update: player Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ControllerMpd::getState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:09 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces state update: player Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ControllerMpd::getState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:09 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces state update: player Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand status took 9 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ControllerMpd::getState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand status took 3 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:09 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces state update: player Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ControllerMpd::getState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand status took 7 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand status took 6 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand status took 6 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand status took 3 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseTrackInfo Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseTrackInfo Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ControllerMpd::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::servicePushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":70,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02 - Bolero (From 'Carmen Suite').ape","artist":null,"album":null,"uri":"NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/02 - Bolero (From 'Carmen Suite').ape","trackType":"ape"} Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: CURRENT POSITION 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState stateService play Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState currentStatus stop Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ControllerMpd::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::servicePushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":70,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02 - Bolero (From 'Carmen Suite').ape","artist":null,"album":null,"uri":"NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/02 - Bolero (From 'Carmen Suite').ape","trackType":"ape"} Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: CURRENT POSITION 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState stateService play Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState currentStatus play Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Received an update from plugin. extracting info from payload Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ------------------------------ 32ms Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ------------------------------ 38ms Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:09 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces state update: player Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ControllerMpd::getState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:09 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces state update: player Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ControllerMpd::getState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand playlistinfo took 33 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand playlistinfo took 33 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand playlistinfo took 33 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand playlistinfo took 33 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand status took 5 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand status took 4 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseTrackInfo Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseTrackInfo Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseTrackInfo Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseTrackInfo Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ControllerMpd::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::servicePushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":70,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02 - Bolero (From 'Carmen Suite').ape","artist":null,"album":null,"uri":"NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/02 - Bolero (From 'Carmen Suite').ape","trackType":"ape"} Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: CURRENT POSITION 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState stateService play Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState currentStatus play Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Received an update from plugin. extracting info from payload Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ControllerMpd::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::servicePushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":70,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02 - Bolero (From 'Carmen Suite').ape","artist":null,"album":null,"uri":"NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/02 - Bolero (From 'Carmen Suite').ape","trackType":"ape"} Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: CURRENT POSITION 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState stateService play Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState currentStatus play Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Received an update from plugin. extracting info from payload Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ControllerMpd::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::servicePushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":70,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02 - Bolero (From 'Carmen Suite').ape","artist":null,"album":null,"uri":"NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/02 - Bolero (From 'Carmen Suite').ape","trackType":"ape"} Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: CURRENT POSITION 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState stateService play Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState currentStatus play Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Received an update from plugin. extracting info from payload Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ControllerMpd::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::servicePushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":70,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02 - Bolero (From 'Carmen Suite').ape","artist":null,"album":null,"uri":"NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/02 - Bolero (From 'Carmen Suite').ape","trackType":"ape"} Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: CURRENT POSITION 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState stateService play Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState currentStatus play Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Received an update from plugin. extracting info from payload Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ------------------------------ 135ms Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ------------------------------ 135ms Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ------------------------------ 135ms Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ------------------------------ 134ms Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand playlistinfo took 117 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: sendMpdCommand playlistinfo took 117 milliseconds Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseTrackInfo Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseTrackInfo Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ControllerMpd::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::servicePushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":70,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02 - Bolero (From 'Carmen Suite').ape","artist":null,"album":null,"uri":"NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/02 - Bolero (From 'Carmen Suite').ape","trackType":"ape"} Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: CURRENT POSITION 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState stateService play Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState currentStatus play Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Received an update from plugin. extracting info from payload Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ControllerMpd::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::servicePushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":70,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02 - Bolero (From 'Carmen Suite').ape","artist":null,"album":null,"uri":"NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/02 - Bolero (From 'Carmen Suite').ape","trackType":"ape"} Oct 05 09:41:09 volumio3ontb16 volumio[1237]: verbose: CURRENT POSITION 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState stateService play Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState currentStatus play Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: Received an update from plugin. extracting info from payload Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ------------------------------ 169ms Oct 05 09:41:09 volumio3ontb16 volumio[1237]: info: ------------------------------ 169ms Oct 05 09:41:10 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioGetState Oct 05 09:41:10 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:17 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioGetState Oct 05 09:41:17 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:18 volumio3ontb16 volumio[1237]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 05 09:41:18 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioGetState Oct 05 09:41:18 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:25 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioGetState Oct 05 09:41:25 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:35 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioGetState Oct 05 09:41:35 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:36 volumio3ontb16 volumio[1237]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 05 09:41:36 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioGetState Oct 05 09:41:36 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:40 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioGetState Oct 05 09:41:40 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPause Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pause Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CoreStateMachine::stPlaybackTimer Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CoreStateMachine::servicePause Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::servicePause Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: ControllerMpd::pause Oct 05 09:41:44 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand pause Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:44 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces state update: player Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: sendMpdCommand pause took 2 milliseconds Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: ControllerMpd::getState Oct 05 09:41:44 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: sendMpdCommand status took 1 milliseconds Oct 05 09:41:44 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseState Oct 05 09:41:44 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:44 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces state update: player Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: ControllerMpd::getState Oct 05 09:41:44 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:44 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces state update: player Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: ControllerMpd::getState Oct 05 09:41:44 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: Oct 05 09:41:44 volumio3ontb16 volumio[1237]: ---------------------------- MPD announces state update: player Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: ControllerMpd::getState Oct 05 09:41:44 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: sendMpdCommand status took 4 milliseconds Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: sendMpdCommand status took 2 milliseconds Oct 05 09:41:44 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseTrackInfo Oct 05 09:41:44 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseState Oct 05 09:41:44 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 09:41:44 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseState Oct 05 09:41:44 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: ControllerMpd::pushState Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::servicePushState Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:44 volumio3ontb16 volumio[1237]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":35538,"duration":70,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02 - Bolero (From 'Carmen Suite').ape","artist":null,"album":null,"uri":"NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/02 - Bolero (From 'Carmen Suite').ape","trackType":"ape"} Oct 05 09:41:44 volumio3ontb16 volumio[1237]: verbose: CURRENT POSITION 1 Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState stateService pause Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState currentStatus pause Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CoreStateMachine::stPlaybackTimer Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: ------------------------------ 27ms Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: sendMpdCommand status took 21 milliseconds Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: sendMpdCommand playlistinfo took 19 milliseconds Oct 05 09:41:44 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseState Oct 05 09:41:44 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 09:41:44 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseTrackInfo Oct 05 09:41:44 volumio3ontb16 volumio[1237]: verbose: ControllerMpd::parseTrackInfo Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: ControllerMpd::pushState Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::servicePushState Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:44 volumio3ontb16 volumio[1237]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":35538,"duration":70,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02 - Bolero (From 'Carmen Suite').ape","artist":null,"album":null,"uri":"NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/02 - Bolero (From 'Carmen Suite').ape","trackType":"ape"} Oct 05 09:41:44 volumio3ontb16 volumio[1237]: verbose: CURRENT POSITION 1 Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState stateService pause Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState currentStatus pause Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CoreStateMachine::stPlaybackTimer Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: ControllerMpd::pushState Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::servicePushState Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:44 volumio3ontb16 volumio[1237]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":35538,"duration":70,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02 - Bolero (From 'Carmen Suite').ape","artist":null,"album":null,"uri":"NAS/音乐集/00CD/24K金音响测试碟 NAXOS 24K/02 - Bolero (From 'Carmen Suite').ape","trackType":"ape"} Oct 05 09:41:44 volumio3ontb16 volumio[1237]: verbose: CURRENT POSITION 1 Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState stateService pause Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CoreStateMachine::syncState currentStatus pause Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CoreStateMachine::pushState Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CorePlayQueue::getTrack 1 Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: CoreStateMachine::stPlaybackTimer Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: ------------------------------ 54ms Oct 05 09:41:44 volumio3ontb16 volumio[1237]: info: ------------------------------ 53ms Oct 05 09:41:44 volumio3ontb16 volumio[1237]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 09:41:44 volumio3ontb16 volumio[1237]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Oct 05 09:41:44 volumio3ontb16 volumio[1237]: at Object.readdirSync (fs.js:1048:3) Oct 05 09:41:44 volumio3ontb16 volumio[1237]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Oct 05 09:41:44 volumio3ontb16 volumio[1237]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Oct 05 09:41:44 volumio3ontb16 volumio[1237]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Oct 05 09:41:44 volumio3ontb16 volumio[1237]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Oct 05 09:41:44 volumio3ontb16 volumio[1237]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Oct 05 09:41:44 volumio3ontb16 volumio[1237]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 05 09:41:44 volumio3ontb16 volumio[1237]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Oct 05 09:41:44 volumio3ontb16 volumio[1237]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Oct 05 09:41:44 volumio3ontb16 volumio[1237]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Oct 05 09:41:44 volumio3ontb16 volumio[1237]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 05 09:41:44 volumio3ontb16 volumio[1237]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Oct 05 09:41:44 volumio3ontb16 volumio[1237]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Oct 05 09:41:44 volumio3ontb16 volumio[1237]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 05 09:41:44 volumio3ontb16 volumio[1237]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Oct 05 09:41:44 volumio3ontb16 volumio[1237]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Oct 05 09:41:44 volumio3ontb16 volumio[1237]: errno: -20, Oct 05 09:41:44 volumio3ontb16 volumio[1237]: syscall: 'scandir', Oct 05 09:41:44 volumio3ontb16 volumio[1237]: code: 'ENOTDIR', Oct 05 09:41:44 volumio3ontb16 volumio[1237]: path: '/tmp/peppyrunning' Oct 05 09:41:44 volumio3ontb16 volumio[1237]: } Oct 05 09:41:44 volumio3ontb16 volumio[1237]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 09:41:44 volumio3ontb16 sudo[2452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-05 09:40 Oct 05 09:41:44 volumio3ontb16 sudo[2452]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"