-- Logs begin at Thu 2019-02-14 19:11:59 JST, end at Sat 2025-08-30 17:35:49 JST. -- Aug 30 17:34:00 volumio volumio[1048]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Aug 30 17:34:00 volumio sh[555]: timed out Aug 30 17:34:00 volumio dhcpcd[676]: timed out Aug 30 17:34:00 volumio sh[555]: dhcpcd exited Aug 30 17:34:00 volumio dhcpcd[676]: dhcpcd exited Aug 30 17:34:00 volumio sh[555]: ifup: failed to bring up eth0 Aug 30 17:34:00 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Aug 30 17:34:00 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Aug 30 17:34:02 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Aug 30 17:34:02 volumio sudo[1330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 17:34:02 volumio sudo[1330]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:34:02 volumio sudo[1330]: pam_unix(sudo:session): session closed for user root Aug 30 17:34:02 volumio sudo[1332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 17:34:02 volumio sudo[1332]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:34:02 volumio sudo[1332]: pam_unix(sudo:session): session closed for user root Aug 30 17:34:02 volumio sudo[1336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 30 17:34:02 volumio sudo[1336]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:34:02 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Aug 30 17:34:02 volumio sudo[1336]: pam_unix(sudo:session): session closed for user root Aug 30 17:34:02 volumio volumio[1048]: info: Upmpdcli Daemon Started Aug 30 17:34:02 volumio volumio[1338]: Generating RSA private key, 4096 bit long modulus (2 primes) Aug 30 17:34:05 volumio volumio[1338]: ...................++++ Aug 30 17:34:07 volumio volumio[1048]: verbose: New Socket.io Connection to 192.168.1.9:3000 from 192.168.1.10 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 17 Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 30 17:34:09 volumio volumio[1048]: info: Adding plugin bluetooth to MyMusic Plugins Aug 30 17:34:09 volumio volumio[1048]: info: Adding plugin multiroom to MyMusic Plugins Aug 30 17:34:09 volumio volumio[1048]: info: Adding plugin metavolumio to MyMusic Plugins Aug 30 17:34:09 volumio volumio[1048]: info: Adding plugin cd_controller to MyMusic Plugins Aug 30 17:34:09 volumio volumio[1048]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 30 17:34:09 volumio volumio[1048]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 30 17:34:09 volumio volumio[1048]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 30 17:34:09 volumio volumio[1048]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 30 17:34:11 volumio volumio[1048]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 30 17:34:11 volumio volumio[1048]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 30 17:34:11 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 17:34:11 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 17:34:11 volumio volumio[1048]: info: Starting MyVolumio Remote Streaming Endpoints Aug 30 17:34:11 volumio volumio[1048]: info: MyVolumio login type: Token Aug 30 17:34:11 volumio volumio[1048]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 30 17:34:11 volumio volumio[1048]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 30 17:34:12 volumio volumio[1048]: info: Starting Streaming Service Transparent Proxy Aug 30 17:34:12 volumio volumio[1048]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 30 17:34:12 volumio volumio[1048]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 30 17:34:12 volumio volumio[1048]: info: Streaming services startup Aug 30 17:34:12 volumio volumio[1048]: info: Starting Streaming Daemon Aug 30 17:34:12 volumio sudo[1375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 30 17:34:12 volumio sudo[1375]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:34:12 volumio sudo[1375]: pam_unix(sudo:session): session closed for user root Aug 30 17:34:12 volumio volumio[1048]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 30 17:34:12 volumio volumio[1048]: error: Cannot start Volumio Streaming Daemon Aug 30 17:34:12 volumio volumio[1048]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 30 17:34:12 volumio volumio[1048]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 30 17:34:12 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 17:34:12 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 17:34:12 volumio volumio[1048]: info: Discovery: Getting this device information Aug 30 17:34:12 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Aug 30 17:34:12 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:12 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 17:34:13 volumio volumio[1048]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 30 17:34:13 volumio volumio[1048]: STREAMING PROXY: Starting server on port 3245 Aug 30 17:34:13 volumio volumio[1048]: Node JS runtime: 14 Aug 30 17:34:15 volumio volumio[1048]: info: MyVolumio token set successfully Aug 30 17:34:15 volumio volumio[1048]: info: MYVOLUMIO: Adding device Aug 30 17:34:15 volumio volumio[1048]: info: MYVOLUMIO: Evaluating Server Aug 30 17:34:16 volumio volumio[1048]: info: MyVolumio status changed Aug 30 17:34:16 volumio volumio[1048]: info: Streaming services startup Aug 30 17:34:16 volumio volumio[1048]: info: Starting Streaming Daemon Aug 30 17:34:16 volumio volumio[1048]: info: Removing browser output: myVolumio user plan is not superstar Aug 30 17:34:16 volumio volumio[1048]: info: Removing audio output: Aug 30 17:34:16 volumio volumio[1048]: info: Stoppping Tunnel 1 Aug 30 17:34:16 volumio sudo[1403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 30 17:34:16 volumio sudo[1403]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:34:16 volumio sudo[1405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 30 17:34:16 volumio sudo[1405]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:34:16 volumio sudo[1403]: pam_unix(sudo:session): session closed for user root Aug 30 17:34:16 volumio volumio[1048]: error: Cannot start Volumio Streaming Daemon Aug 30 17:34:16 volumio volumio[1048]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 30 17:34:16 volumio volumio[1048]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 30 17:34:16 volumio sudo[1405]: pam_unix(sudo:session): session closed for user root Aug 30 17:34:16 volumio volumio[1048]: info: Remote SSH Stopped Aug 30 17:34:17 volumio systemd[1]: systemd-timedated.service: Succeeded. Aug 30 17:34:21 volumio volumio[1048]: info: Setting Geolocation for MyVolumio to as1 Aug 30 17:34:21 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 17:34:22 volumio volumio[1048]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 30 17:34:22 volumio volumio[1048]: info: Updating MyVolumio device info Aug 30 17:34:22 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 17:34:23 volumio volumio[1048]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 30 17:34:26 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 17:34:26 volumio volumio[1048]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Aug 30 17:34:26 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Aug 30 17:34:26 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:26 volumio volumio[1048]: info: MYVOLUMIO: Adding device Aug 30 17:34:26 volumio volumio[1048]: info: MYVOLUMIO: Evaluating Server Aug 30 17:34:27 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 17:34:27 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 17:34:27 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 17:34:27 volumio volumio[1048]: info: Setting Geolocation for MyVolumio to us1 Aug 30 17:34:27 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 17:34:27 volumio volumio-remote-updater[576]: No test mode Aug 30 17:34:27 volumio volumio-remote-updater[576]: No alpha test mode Aug 30 17:34:27 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 30 17:34:28 volumio volumio[1048]: info: Updating MyVolumio device info Aug 30 17:34:28 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 17:34:29 volumio volumio[1048]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 30 17:34:29 volumio volumio[1048]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 30 17:34:29 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 30 17:34:29 volumio volumio[1048]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 30 17:34:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:34:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 30 17:34:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 30 17:34:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 30 17:34:31 volumio volumio[1048]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 17:34:31 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 17:34:32 volumio volumio[1048]: error: MyVolumio Plugin failed to authenticate in a timely fashion Aug 30 17:34:32 volumio volumio[1048]: info: Completed starting MyVolumio Plugin Aug 30 17:34:32 volumio volumio[1048]: [Metrics] CommandRouter: 43s 848.75ms Aug 30 17:34:32 volumio volumio[1048]: info: CoreCommandRouter::volumiosetStartupVolume Aug 30 17:34:32 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:34:32 volumio volumio[1048]: info: VolumeController:: Setting startup Volume 50 Aug 30 17:34:32 volumio volumio[1048]: info: VolumeController::SetAlsaVolume50 Aug 30 17:34:32 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 17:34:32 volumio volumio[1048]: info: CoreCommandRouter::Close All Modals sent Aug 30 17:34:32 volumio volumio[1048]: info: CoreCommandRouter::Close All Modals sent Aug 30 17:34:32 volumio volumio[1048]: info: CoreStateMachine::pushState Aug 30 17:34:32 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:32 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 17:34:32 volumio volumio[1048]: info: CoreCommandRouter::volumioPushState Aug 30 17:34:32 volumio volumio[1048]: info: [GPIO_Control] Status changed: stop Aug 30 17:34:32 volumio volumio[1048]: info: [GPIO_Control] Handling event: musicStop Aug 30 17:34:32 volumio volumio[1048]: info: [GPIO_Control] Handling event: repeatAllOff Aug 30 17:34:32 volumio volumio[1048]: info: [GPIO_Control] Handling event: repeatOff Aug 30 17:34:32 volumio volumio[1048]: info: [GPIO_Control] Status changed: stop Aug 30 17:34:33 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 30 17:34:33 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 30 17:34:33 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 30 17:34:34 volumio volumio[1338]: ....................................................................................................................................................................................................................++++ Aug 30 17:34:34 volumio volumio[1338]: e is 65537 (0x010001) Aug 30 17:34:34 volumio volumio[1338]: writing RSA key Aug 30 17:34:35 volumio volumio[1048]: info: Preload queue cleared Aug 30 17:34:37 volumio ntpd[851]: Soliciting pool server 129.250.35.251 Aug 30 17:34:37 volumio ntpd[851]: Soliciting pool server 162.159.200.1 Aug 30 17:34:37 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 30 17:34:37 volumio volumio[1048]: info: CURURI: albums:// Aug 30 17:34:37 volumio volumio[1048]: info: listAlbums - loading Albums from cache Aug 30 17:34:37 volumio volumio[1048]: info: Preload queue cleared Aug 30 17:34:38 volumio ntpd[851]: Soliciting pool server 103.131.151.20 Aug 30 17:34:39 volumio volumio[1048]: info: Preload queue cleared Aug 30 17:34:39 volumio volumio[1048]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::ClearQueue Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::stop Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 17:34:39 volumio volumio[1048]: info: CorePlayQueue::clearPlayQueue Aug 30 17:34:39 volumio volumio[1048]: info: CorePlayQueue::saveQueue Aug 30 17:34:39 volumio volumio[1048]: info: CoreCommandRouter::volumioPushQueue Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::addQueueItems Aug 30 17:34:39 volumio volumio[1048]: info: CorePlayQueue::addQueueItems Aug 30 17:34:39 volumio volumio[1048]: info: Preload queue cleared Aug 30 17:34:39 volumio volumio[1048]: info: Adding Item to queue: albums://ABBA/Gold%3A%20Greatest%20Hits Aug 30 17:34:39 volumio volumio[1048]: info: Exploding uri albums://ABBA/Gold%3A%20Greatest%20Hits in service mpd Aug 30 17:34:39 volumio volumio[1048]: info: CoreCommandRouter::volumioPushQueue Aug 30 17:34:39 volumio volumio[1048]: info: CorePlayQueue::saveQueue Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::updateTrackBlock Aug 30 17:34:39 volumio volumio[1048]: info: CorePlayQueue::getTrackBlock Aug 30 17:34:39 volumio volumio[1048]: info: CoreCommandRouter::volumioPlay Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::play index 0 Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::stop Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::play index undefined Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 17:34:39 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::startPlaybackTimer Aug 30 17:34:39 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::clearAddPlayTracks USB/VolumioData/FLAC Music Data/ABBA/Gold Greatest Hits/1-01 Dancing Queen.flac Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 17:34:39 volumio volumio[1048]: info: sendMpdCommand stop took 8 milliseconds Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 17:34:39 volumio volumio[1048]: info: Aug 30 17:34:39 volumio volumio[1048]: ---------------------------- MPD announces system playlist update Aug 30 17:34:39 volumio volumio[1048]: info: Ignoring MPD Status Update Aug 30 17:34:39 volumio volumio[1048]: info: sendMpdCommand clear took 4 milliseconds Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand add "USB/VolumioData/FLAC Music Data/ABBA/Gold Greatest Hits/1-01 Dancing Queen.flac" Aug 30 17:34:39 volumio volumio[1048]: info: Aug 30 17:34:39 volumio volumio[1048]: ---------------------------- MPD announces system playlist update Aug 30 17:34:39 volumio volumio[1048]: info: Ignoring MPD Status Update Aug 30 17:34:39 volumio volumio[1048]: info: Aug 30 17:34:39 volumio volumio[1048]: ---------------------------- MPD announces system playlist update Aug 30 17:34:39 volumio volumio[1048]: info: Ignoring MPD Status Update Aug 30 17:34:39 volumio volumio[1048]: error: updateQueue error: null Aug 30 17:34:39 volumio volumio[1048]: info: Aug 30 17:34:39 volumio volumio[1048]: ---------------------------- MPD announces system playlist update Aug 30 17:34:39 volumio volumio[1048]: info: Ignoring MPD Status Update Aug 30 17:34:39 volumio volumio[1048]: info: ------------------------------ 14ms Aug 30 17:34:39 volumio volumio[1048]: info: sendMpdCommand add "USB/VolumioData/FLAC Music Data/ABBA/Gold Greatest Hits/1-01 Dancing Queen.flac" took 11 milliseconds Aug 30 17:34:39 volumio volumio[1048]: info: ------------------------------ 10ms Aug 30 17:34:39 volumio volumio[1048]: info: ------------------------------ 8ms Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand play Aug 30 17:34:39 volumio volumio[1048]: info: Aug 30 17:34:39 volumio volumio[1048]: ---------------------------- MPD announces system playlist update Aug 30 17:34:39 volumio volumio[1048]: info: Ignoring MPD Status Update Aug 30 17:34:39 volumio volumio[1048]: info: Aug 30 17:34:39 volumio volumio[1048]: ---------------------------- MPD announces system playlist update Aug 30 17:34:39 volumio volumio[1048]: info: Ignoring MPD Status Update Aug 30 17:34:39 volumio volumio[1048]: info: Aug 30 17:34:39 volumio volumio[1048]: ---------------------------- MPD announces state update: player Aug 30 17:34:39 volumio volumio[1048]: info: ------------------------------ 14ms Aug 30 17:34:39 volumio volumio[1048]: info: sendMpdCommand play took 10 milliseconds Aug 30 17:34:39 volumio volumio[1048]: info: ------------------------------ 9ms Aug 30 17:34:39 volumio volumio[1048]: info: ------------------------------ 6ms Aug 30 17:34:39 volumio volumio[1048]: info: ControllerMpd::getState Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Aug 30 17:34:39 volumio volumio[1048]: info: Aug 30 17:34:39 volumio volumio[1048]: ---------------------------- MPD announces state update: player Aug 30 17:34:39 volumio volumio[1048]: info: ControllerMpd::getState Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Aug 30 17:34:39 volumio volumio[1048]: info: Aug 30 17:34:39 volumio volumio[1048]: ---------------------------- MPD announces state update: player Aug 30 17:34:39 volumio volumio[1048]: info: ControllerMpd::getState Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Aug 30 17:34:39 volumio volumio[1048]: info: Aug 30 17:34:39 volumio volumio[1048]: ---------------------------- MPD announces state update: player Aug 30 17:34:39 volumio volumio[1048]: info: ControllerMpd::getState Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Aug 30 17:34:39 volumio volumio[1048]: info: Aug 30 17:34:39 volumio volumio[1048]: ---------------------------- MPD announces state update: player Aug 30 17:34:39 volumio volumio[1048]: info: ControllerMpd::getState Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Aug 30 17:34:39 volumio volumio[1048]: info: Aug 30 17:34:39 volumio volumio[1048]: ---------------------------- MPD announces state update: player Aug 30 17:34:39 volumio volumio[1048]: info: ControllerMpd::getState Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Aug 30 17:34:39 volumio volumio[1048]: info: sendMpdCommand status took 37 milliseconds Aug 30 17:34:39 volumio volumio[1048]: info: sendMpdCommand status took 35 milliseconds Aug 30 17:34:39 volumio volumio[1048]: info: sendMpdCommand status took 34 milliseconds Aug 30 17:34:39 volumio volumio[1048]: info: sendMpdCommand status took 14 milliseconds Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::parseState Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::parseState Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::parseState Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 17:34:39 volumio ntpd[851]: Soliciting pool server 108.160.132.224 Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::parseState Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 17:34:39 volumio volumio[1048]: info: sendMpdCommand status took 27 milliseconds Aug 30 17:34:39 volumio volumio[1048]: info: sendMpdCommand status took 23 milliseconds Aug 30 17:34:39 volumio volumio[1048]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 30 17:34:39 volumio volumio[1048]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 30 17:34:39 volumio volumio[1048]: info: sendMpdCommand playlistinfo took 10 milliseconds Aug 30 17:34:39 volumio volumio[1048]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::parseState Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::parseState Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::parseTrackInfo Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::parseTrackInfo Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::parseTrackInfo Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::parseTrackInfo Aug 30 17:34:39 volumio volumio[1048]: info: ControllerMpd::pushState Aug 30 17:34:39 volumio volumio[1048]: info: CoreCommandRouter::servicePushState Aug 30 17:34:39 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:39 volumio volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1092 Kbps","isStreaming":false,"title":"Dancing Queen","artist":"ABBA","album":"Gold: Greatest Hits","uri":"USB/VolumioData/FLAC Music Data/ABBA/Gold Greatest Hits/1-01 Dancing Queen.flac","trackType":"flac"} Aug 30 17:34:39 volumio volumio[1048]: verbose: CURRENT POSITION 0 Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::syncState stateService play Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::syncState currentStatus stop Aug 30 17:34:39 volumio volumio[1048]: info: ControllerMpd::pushState Aug 30 17:34:39 volumio volumio[1048]: info: CoreCommandRouter::servicePushState Aug 30 17:34:39 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:39 volumio volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1092 Kbps","isStreaming":false,"title":"Dancing Queen","artist":"ABBA","album":"Gold: Greatest Hits","uri":"USB/VolumioData/FLAC Music Data/ABBA/Gold Greatest Hits/1-01 Dancing Queen.flac","trackType":"flac"} Aug 30 17:34:39 volumio volumio[1048]: verbose: CURRENT POSITION 0 Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::syncState stateService play Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::syncState currentStatus play Aug 30 17:34:39 volumio volumio[1048]: info: Received an update from plugin. extracting info from payload Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::pushState Aug 30 17:34:39 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:39 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 17:34:39 volumio volumio[1048]: info: CoreCommandRouter::volumioPushState Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::pushState Aug 30 17:34:39 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:39 volumio volumio[1048]: info: CoreCommandRouter::volumioPushState Aug 30 17:34:39 volumio volumio[1048]: info: ControllerMpd::pushState Aug 30 17:34:39 volumio volumio[1048]: info: CoreCommandRouter::servicePushState Aug 30 17:34:39 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:39 volumio volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1092 Kbps","isStreaming":false,"title":"Dancing Queen","artist":"ABBA","album":"Gold: Greatest Hits","uri":"USB/VolumioData/FLAC Music Data/ABBA/Gold Greatest Hits/1-01 Dancing Queen.flac","trackType":"flac"} Aug 30 17:34:39 volumio volumio[1048]: verbose: CURRENT POSITION 0 Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::syncState stateService play Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::syncState currentStatus play Aug 30 17:34:39 volumio volumio[1048]: info: Received an update from plugin. extracting info from payload Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::pushState Aug 30 17:34:39 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:39 volumio volumio[1048]: info: CoreCommandRouter::volumioPushState Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::pushState Aug 30 17:34:39 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:39 volumio volumio[1048]: info: CoreCommandRouter::volumioPushState Aug 30 17:34:39 volumio volumio[1048]: info: ControllerMpd::pushState Aug 30 17:34:39 volumio volumio[1048]: info: CoreCommandRouter::servicePushState Aug 30 17:34:39 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:39 volumio volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1092 Kbps","isStreaming":false,"title":"Dancing Queen","artist":"ABBA","album":"Gold: Greatest Hits","uri":"USB/VolumioData/FLAC Music Data/ABBA/Gold Greatest Hits/1-01 Dancing Queen.flac","trackType":"flac"} Aug 30 17:34:39 volumio volumio[1048]: verbose: CURRENT POSITION 0 Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::syncState stateService play Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::syncState currentStatus play Aug 30 17:34:39 volumio volumio[1048]: info: Received an update from plugin. extracting info from payload Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::pushState Aug 30 17:34:39 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:39 volumio volumio[1048]: info: CoreCommandRouter::volumioPushState Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::pushState Aug 30 17:34:39 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:39 volumio volumio[1048]: info: CoreCommandRouter::volumioPushState Aug 30 17:34:39 volumio volumio[1048]: info: ------------------------------ 124ms Aug 30 17:34:39 volumio volumio[1048]: info: ------------------------------ 171ms Aug 30 17:34:39 volumio volumio[1048]: info: ------------------------------ 169ms Aug 30 17:34:39 volumio volumio[1048]: info: ------------------------------ 151ms Aug 30 17:34:39 volumio volumio[1048]: info: BOOT COMPLETED Aug 30 17:34:39 volumio volumio[1048]: info: sendMpdCommand playlistinfo took 116 milliseconds Aug 30 17:34:39 volumio volumio[1048]: info: sendMpdCommand playlistinfo took 115 milliseconds Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::parseTrackInfo Aug 30 17:34:39 volumio volumio[1048]: verbose: ControllerMpd::parseTrackInfo Aug 30 17:34:39 volumio volumio[1048]: info: ControllerMpd::pushState Aug 30 17:34:39 volumio volumio[1048]: info: CoreCommandRouter::servicePushState Aug 30 17:34:39 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:39 volumio volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1092 Kbps","isStreaming":false,"title":"Dancing Queen","artist":"ABBA","album":"Gold: Greatest Hits","uri":"USB/VolumioData/FLAC Music Data/ABBA/Gold Greatest Hits/1-01 Dancing Queen.flac","trackType":"flac"} Aug 30 17:34:39 volumio volumio[1048]: verbose: CURRENT POSITION 0 Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::syncState stateService play Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::syncState currentStatus play Aug 30 17:34:39 volumio volumio[1048]: info: Received an update from plugin. extracting info from payload Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::pushState Aug 30 17:34:39 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:39 volumio volumio[1048]: info: CoreCommandRouter::volumioPushState Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::pushState Aug 30 17:34:39 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:39 volumio volumio[1048]: info: CoreCommandRouter::volumioPushState Aug 30 17:34:39 volumio volumio[1048]: info: ControllerMpd::pushState Aug 30 17:34:39 volumio volumio[1048]: info: CoreCommandRouter::servicePushState Aug 30 17:34:39 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:39 volumio volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1092 Kbps","isStreaming":false,"title":"Dancing Queen","artist":"ABBA","album":"Gold: Greatest Hits","uri":"USB/VolumioData/FLAC Music Data/ABBA/Gold Greatest Hits/1-01 Dancing Queen.flac","trackType":"flac"} Aug 30 17:34:39 volumio volumio[1048]: verbose: CURRENT POSITION 0 Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::syncState stateService play Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::syncState currentStatus play Aug 30 17:34:39 volumio volumio[1048]: info: Received an update from plugin. extracting info from payload Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::pushState Aug 30 17:34:39 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:39 volumio volumio[1048]: info: CoreCommandRouter::volumioPushState Aug 30 17:34:39 volumio volumio[1048]: info: CoreStateMachine::pushState Aug 30 17:34:39 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:39 volumio volumio[1048]: info: CoreCommandRouter::volumioPushState Aug 30 17:34:39 volumio volumio[1048]: info: ------------------------------ 221ms Aug 30 17:34:39 volumio volumio[1048]: info: ------------------------------ 217ms Aug 30 17:34:39 volumio volumio[1048]: info: [GPIO_Control] Status changed: play Aug 30 17:34:39 volumio volumio[1048]: info: [GPIO_Control] Handling event: musicPlay Aug 30 17:34:39 volumio volumio[1048]: info: [GPIO_Control] Status changed: play Aug 30 17:34:39 volumio volumio[1048]: info: [GPIO_Control] Status changed: play Aug 30 17:34:39 volumio volumio[1048]: info: [GPIO_Control] Status changed: play Aug 30 17:34:39 volumio volumio[1048]: info: [GPIO_Control] Status changed: play Aug 30 17:34:39 volumio volumio[1048]: info: [GPIO_Control] Status changed: play Aug 30 17:34:39 volumio volumio[1048]: info: [GPIO_Control] Status changed: play Aug 30 17:34:39 volumio volumio[1048]: info: [GPIO_Control] Status changed: play Aug 30 17:34:39 volumio volumio[1048]: info: [GPIO_Control] Status changed: play Aug 30 17:34:39 volumio volumio[1048]: info: [GPIO_Control] Status changed: play Aug 30 17:34:41 volumio ntpd[851]: Soliciting pool server 160.16.113.133 Aug 30 17:34:41 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 17:34:41 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 17:34:41 volumio volumio[1048]: info: Discovery: Getting this device information Aug 30 17:34:41 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Aug 30 17:34:41 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:41 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 17:34:41 volumio ntpd[851]: Soliciting pool server 133.130.121.141 Aug 30 17:34:41 volumio ntpd[851]: Soliciting pool server 167.179.119.205 Aug 30 17:34:41 volumio ntpd[851]: Soliciting pool server 162.159.200.123 Aug 30 17:34:42 volumio ntpd[851]: Soliciting pool server 45.76.211.39 Aug 30 17:34:42 volumio ntpd[851]: Soliciting pool server 142.91.105.55 Aug 30 17:34:42 volumio volumio[1048]: info: CoreCommandRouter::volumioPause Aug 30 17:34:42 volumio volumio[1048]: info: CoreStateMachine::pause Aug 30 17:34:42 volumio volumio[1048]: info: CoreStateMachine::stPlaybackTimer Aug 30 17:34:42 volumio volumio[1048]: info: CoreStateMachine::servicePause Aug 30 17:34:42 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:42 volumio volumio[1048]: info: CoreCommandRouter::servicePause Aug 30 17:34:42 volumio volumio[1048]: info: ControllerMpd::pause Aug 30 17:34:42 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand pause Aug 30 17:34:42 volumio volumio[1048]: info: sendMpdCommand pause took 1 milliseconds Aug 30 17:34:42 volumio volumio[1048]: info: Aug 30 17:34:42 volumio volumio[1048]: ---------------------------- MPD announces state update: player Aug 30 17:34:42 volumio volumio[1048]: info: ControllerMpd::getState Aug 30 17:34:42 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Aug 30 17:34:42 volumio volumio[1048]: info: Aug 30 17:34:42 volumio volumio[1048]: ---------------------------- MPD announces state update: player Aug 30 17:34:42 volumio volumio[1048]: info: ControllerMpd::getState Aug 30 17:34:42 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Aug 30 17:34:42 volumio volumio[1048]: info: Aug 30 17:34:42 volumio volumio[1048]: ---------------------------- MPD announces state update: player Aug 30 17:34:42 volumio volumio[1048]: info: ControllerMpd::getState Aug 30 17:34:42 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Aug 30 17:34:42 volumio volumio[1048]: info: sendMpdCommand status took 6 milliseconds Aug 30 17:34:42 volumio volumio[1048]: info: sendMpdCommand status took 4 milliseconds Aug 30 17:34:42 volumio volumio[1048]: info: sendMpdCommand status took 3 milliseconds Aug 30 17:34:42 volumio volumio[1048]: verbose: ControllerMpd::parseState Aug 30 17:34:42 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 17:34:42 volumio volumio[1048]: verbose: ControllerMpd::parseState Aug 30 17:34:42 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 17:34:42 volumio volumio[1048]: verbose: ControllerMpd::parseState Aug 30 17:34:42 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 17:34:42 volumio volumio[1048]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 30 17:34:42 volumio volumio[1048]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 30 17:34:42 volumio volumio[1048]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 30 17:34:42 volumio volumio[1048]: verbose: ControllerMpd::parseTrackInfo Aug 30 17:34:42 volumio volumio[1048]: verbose: ControllerMpd::parseTrackInfo Aug 30 17:34:42 volumio volumio[1048]: verbose: ControllerMpd::parseTrackInfo Aug 30 17:34:42 volumio volumio[1048]: info: ControllerMpd::pushState Aug 30 17:34:42 volumio volumio[1048]: info: CoreCommandRouter::servicePushState Aug 30 17:34:42 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:42 volumio volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3958,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1024 Kbps","isStreaming":false,"title":"Dancing Queen","artist":"ABBA","album":"Gold: Greatest Hits","uri":"USB/VolumioData/FLAC Music Data/ABBA/Gold Greatest Hits/1-01 Dancing Queen.flac","trackType":"flac"} Aug 30 17:34:42 volumio volumio[1048]: verbose: CURRENT POSITION 0 Aug 30 17:34:42 volumio volumio[1048]: info: CoreStateMachine::syncState stateService pause Aug 30 17:34:42 volumio volumio[1048]: info: CoreStateMachine::syncState currentStatus pause Aug 30 17:34:42 volumio volumio[1048]: info: CoreStateMachine::pushState Aug 30 17:34:42 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:42 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 17:34:42 volumio volumio[1048]: info: CoreCommandRouter::volumioPushState Aug 30 17:34:42 volumio volumio[1048]: info: CoreStateMachine::stPlaybackTimer Aug 30 17:34:42 volumio volumio[1048]: info: ControllerMpd::pushState Aug 30 17:34:42 volumio volumio[1048]: info: CoreCommandRouter::servicePushState Aug 30 17:34:42 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:42 volumio volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3958,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1024 Kbps","isStreaming":false,"title":"Dancing Queen","artist":"ABBA","album":"Gold: Greatest Hits","uri":"USB/VolumioData/FLAC Music Data/ABBA/Gold Greatest Hits/1-01 Dancing Queen.flac","trackType":"flac"} Aug 30 17:34:42 volumio volumio[1048]: verbose: CURRENT POSITION 0 Aug 30 17:34:42 volumio volumio[1048]: info: CoreStateMachine::syncState stateService pause Aug 30 17:34:42 volumio volumio[1048]: info: CoreStateMachine::syncState currentStatus pause Aug 30 17:34:42 volumio volumio[1048]: info: CoreStateMachine::pushState Aug 30 17:34:42 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:42 volumio volumio[1048]: info: CoreCommandRouter::volumioPushState Aug 30 17:34:42 volumio volumio[1048]: info: CoreStateMachine::stPlaybackTimer Aug 30 17:34:42 volumio volumio[1048]: info: ControllerMpd::pushState Aug 30 17:34:42 volumio volumio[1048]: info: CoreCommandRouter::servicePushState Aug 30 17:34:42 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:42 volumio volumio[1048]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3958,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1024 Kbps","isStreaming":false,"title":"Dancing Queen","artist":"ABBA","album":"Gold: Greatest Hits","uri":"USB/VolumioData/FLAC Music Data/ABBA/Gold Greatest Hits/1-01 Dancing Queen.flac","trackType":"flac"} Aug 30 17:34:42 volumio volumio[1048]: verbose: CURRENT POSITION 0 Aug 30 17:34:42 volumio volumio[1048]: info: CoreStateMachine::syncState stateService pause Aug 30 17:34:42 volumio volumio[1048]: info: CoreStateMachine::syncState currentStatus pause Aug 30 17:34:42 volumio volumio[1048]: info: CoreStateMachine::pushState Aug 30 17:34:42 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:34:42 volumio volumio[1048]: info: CoreCommandRouter::volumioPushState Aug 30 17:34:42 volumio volumio[1048]: info: CoreStateMachine::stPlaybackTimer Aug 30 17:34:42 volumio volumio[1048]: info: ------------------------------ 65ms Aug 30 17:34:42 volumio volumio[1048]: info: ------------------------------ 63ms Aug 30 17:34:42 volumio volumio[1048]: info: ------------------------------ 62ms Aug 30 17:34:42 volumio volumio[1048]: info: [GPIO_Control] Status changed: pause Aug 30 17:34:42 volumio volumio[1048]: info: [GPIO_Control] Handling event: musicPause Aug 30 17:34:42 volumio volumio[1048]: info: [GPIO_Control] Status changed: pause Aug 30 17:34:42 volumio volumio[1048]: info: [GPIO_Control] Status changed: pause Aug 30 17:34:43 volumio ntpd[851]: Soliciting pool server 46.232.60.193 Aug 30 17:34:43 volumio ntpd[851]: Soliciting pool server 45.77.20.103 Aug 30 17:34:44 volumio ntpd[851]: Soliciting pool server 2001:418:3ff::53 Aug 30 17:34:45 volumio ntpd[851]: receive: Unexpected origin timestamp 0xec5d3624.b40a6325 does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xec5d3625.d6982b12 Aug 30 17:34:45 volumio ntpd[851]: receive: Unexpected origin timestamp 0xec5d3624.b4112657 does not match aorg 0000000000.00000000 from server@103.131.151.20 xmt 0xec5d3625.d64712e4 Aug 30 17:34:45 volumio ntpd[851]: receive: Unexpected origin timestamp 0xec5d3624.b413783a does not match aorg 0000000000.00000000 from server@129.250.35.251 xmt 0xec5d3625.db9d7220 Aug 30 17:34:50 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 17:34:50 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 30 17:34:59 volumio volumio[1048]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/touch_display/volumio/buster/armhf Aug 30 17:35:02 volumio volumio[1048]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/touch_display/volumio/buster/armhf Aug 30 17:35:02 volumio volumio[1048]: info: Folder /tmp/plugins removed Aug 30 17:35:02 volumio volumio[1048]: info: Check plugin dependencies Aug 30 17:35:02 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 17:35:02 volumio volumio[1048]: info: Checking if plugin already exists Aug 30 17:35:02 volumio volumio[1048]: info: Rename folder Aug 30 17:35:02 volumio volumio[1048]: info: Folder /tmp/downloaded_plugin.zip removed Aug 30 17:35:02 volumio volumio[1048]: info: Move to category Aug 30 17:35:02 volumio volumio[1048]: info: Checking if install.sh is present Aug 30 17:35:02 volumio volumio[1048]: info: Executing install.sh Aug 30 17:35:02 volumio sudo[1578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/touch_display/install.sh Aug 30 17:35:02 volumio sudo[1578]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:35:03 volumio volumio[1048]: info: Re-synchronizing package index files from their sources Aug 30 17:35:05 volumio volumio[1048]: info: Hit:1 https://deb.nodesource.com/node_14.x buster InRelease Aug 30 17:35:05 volumio volumio[1048]: info: Hit:2 http://archive.raspberrypi.org/debian buster InRelease Aug 30 17:35:05 volumio volumio[1048]: info: Hit:3 http://raspbian.raspberrypi.org/raspbian buster InRelease Aug 30 17:35:11 volumio volumio[1048]: info: Reading package lists... Aug 30 17:35:11 volumio volumio[1048]: info: Reading package lists... Aug 30 17:35:12 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 17:35:12 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 17:35:12 volumio volumio[1048]: info: Discovery: Getting this device information Aug 30 17:35:12 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Aug 30 17:35:12 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:35:12 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 17:35:12 volumio volumio[1048]: info: Building dependency tree... Aug 30 17:35:12 volumio volumio[1048]: info: Reading state information... Aug 30 17:35:17 volumio volumio[1048]: info: 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. Aug 30 17:35:17 volumio volumio[1048]: info: Installing graphical environment Aug 30 17:35:17 volumio volumio[1048]: info: Reading package lists... Aug 30 17:35:18 volumio volumio[1048]: info: Building dependency tree... Aug 30 17:35:18 volumio volumio[1048]: info: Reading state information... Aug 30 17:35:19 volumio volumio[1048]: info: xinit is already the newest version (1.4.0-1). Aug 30 17:35:19 volumio volumio[1048]: info: 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. Aug 30 17:35:19 volumio volumio[1048]: info: Reading package lists... Aug 30 17:35:20 volumio volumio[1048]: info: Building dependency tree... Aug 30 17:35:20 volumio volumio[1048]: info: Reading state information... Aug 30 17:35:22 volumio volumio[1048]: info: xorg is already the newest version (1:7.7+19+b8). Aug 30 17:35:22 volumio volumio[1048]: info: 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. Aug 30 17:35:22 volumio volumio[1048]: info: Reading package lists... Aug 30 17:35:23 volumio volumio[1048]: info: Building dependency tree... Aug 30 17:35:23 volumio volumio[1048]: info: Reading state information... Aug 30 17:35:24 volumio volumio[1048]: info: openbox is already the newest version (3.6.1-8+rpt5). Aug 30 17:35:24 volumio volumio[1048]: info: 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. Aug 30 17:35:24 volumio volumio[1048]: info: Creating /etc/X11/xorg.conf.d dir Aug 30 17:35:24 volumio volumio[1048]: info: Creating Xorg configuration Aug 30 17:35:24 volumio volumio[1048]: info: Installing Chromium Aug 30 17:35:24 volumio volumio[1048]: info: Reading package lists... Aug 30 17:35:25 volumio volumio[1048]: info: Building dependency tree... Aug 30 17:35:25 volumio volumio[1048]: info: Reading state information... Aug 30 17:35:27 volumio volumio[1048]: info: chromium-browser is already the newest version (92.0.4515.98~buster-rpt2). Aug 30 17:35:27 volumio volumio[1048]: info: 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. Aug 30 17:35:27 volumio volumio[1048]: info: Installing fonts Aug 30 17:35:27 volumio volumio[1048]: info: Reading package lists... Aug 30 17:35:28 volumio volumio[1048]: info: Building dependency tree... Aug 30 17:35:28 volumio volumio[1048]: info: Reading state information... Aug 30 17:35:29 volumio volumio[1048]: info: fonts-arphic-gbsn00lp is already the newest version (2.11-15). Aug 30 17:35:29 volumio volumio[1048]: info: fonts-arphic-ukai is already the newest version (0.2.20080216.2-4). Aug 30 17:35:29 volumio volumio[1048]: info: fonts-ipafont is already the newest version (00303-18). Aug 30 17:35:29 volumio volumio[1048]: info: fonts-thai-tlwg-ttf is already the newest version (1:0.7.1-1). Aug 30 17:35:29 volumio volumio[1048]: info: fonts-unfonts-core is already the newest version (1:1.0.2-080608-16). Aug 30 17:35:29 volumio volumio[1048]: info: fonts-vlgothic is already the newest version (20141206-5). Aug 30 17:35:29 volumio volumio[1048]: info: 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. Aug 30 17:35:29 volumio systemd[1]: Reloading. Aug 30 17:35:30 volumio volumio[1048]: info: Creating Kiosk data dir Aug 30 17:35:30 volumio volumio[1048]: info: Creating Volumio kiosk start script Aug 30 17:35:30 volumio volumio[1048]: info: Creating Systemd Unit for Kiosk Aug 30 17:35:30 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 30 17:35:30 volumio volumio[1048]: info: Disabling login prompt Aug 30 17:35:30 volumio systemd[1]: Reloading. Aug 30 17:35:31 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 30 17:35:31 volumio volumio[1048]: info: Installing Virtual Keyboard Aug 30 17:35:33 volumio volumio[1048]: info: Allowing volumio to start an xsession Aug 30 17:35:33 volumio sudo[1578]: pam_unix(sudo:session): session closed for user root Aug 30 17:35:33 volumio volumio[1048]: info: Removing temporary directory /tmp/touch_display-ojFpnb6hBv Aug 30 17:35:33 volumio volumio[1048]: Plugin install end detected on script Aug 30 17:35:33 volumio volumio[1048]: info: Install script completed Aug 30 17:35:33 volumio volumio[1048]: info: Adding reference to registry Aug 30 17:35:33 volumio volumio[1048]: info: Done installing plugin. Aug 30 17:35:33 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 17:35:33 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 30 17:35:33 volumio volumio[1048]: info: Folder /tmp/plugins removed Aug 30 17:35:33 volumio volumio[1048]: info: Folder /tmp/downloaded_plugin.zip removed Aug 30 17:35:33 volumio volumio[1048]: info: Folder /data/temp removed Aug 30 17:35:37 volumio volumio[1048]: info: Enabling plugin touch_display Aug 30 17:35:37 volumio volumio[1048]: info: Loading plugin "touch_display"... Aug 30 17:35:37 volumio volumio[1048]: info: PLUGIN START: touch_display Aug 30 17:35:38 volumio volumio[1048]: info: Loading i18n strings for locale ja Aug 30 17:35:38 volumio volumio[1048]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Aug 30 17:35:38 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 17:35:38 volumio sudo[1958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Aug 30 17:35:38 volumio sudo[1958]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:35:38 volumio sudo[1960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Aug 30 17:35:38 volumio sudo[1960]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:35:38 volumio volumio[1048]: info: touch_display: Backlight interface detected. Aug 30 17:35:38 volumio sudo[1963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 30 17:35:38 volumio volumio[1048]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 30 17:35:38 volumio sudo[1963]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:35:38 volumio systemd[1]: Reloading. Aug 30 17:35:38 volumio volumio[1048]: info: touch_display: Raspberry Pi Foundation touch screen detected. Aug 30 17:35:38 volumio sudo[1967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/rpi_backlight/brightness Aug 30 17:35:38 volumio sudo[1967]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:35:38 volumio sudo[1967]: pam_unix(sudo:session): session closed for user root Aug 30 17:35:38 volumio sudo[1969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Aug 30 17:35:38 volumio sudo[1969]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:35:38 volumio sudo[1969]: pam_unix(sudo:session): session closed for user root Aug 30 17:35:38 volumio volumio[1048]: info: touch_display: File permissions for backlight brightness control set. Aug 30 17:35:38 volumio volumio[1048]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Aug 30 17:35:38 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 30 17:35:39 volumio systemd[1]: Reloading. Aug 30 17:35:39 volumio sudo[1960]: pam_unix(sudo:session): session closed for user root Aug 30 17:35:39 volumio volumio[1048]: info: touch_display: systemctl disable getty@tty1.service succeeded. Aug 30 17:35:39 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 30 17:35:39 volumio sudo[1963]: pam_unix(sudo:session): session closed for user root Aug 30 17:35:39 volumio volumio[1048]: info: touch_display: systemctl daemon-reload succeeded. Aug 30 17:35:39 volumio sudo[2001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Aug 30 17:35:39 volumio sudo[1958]: pam_unix(sudo:session): session closed for user root Aug 30 17:35:39 volumio sudo[2001]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:35:39 volumio volumio[1048]: info: touch_display: systemctl stop getty@tty1.service succeeded. Aug 30 17:35:39 volumio systemd[1]: Started Volumio Kiosk. Aug 30 17:35:39 volumio sudo[2001]: pam_unix(sudo:session): session closed for user root Aug 30 17:35:39 volumio volumio[1048]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Aug 30 17:35:39 volumio volumio[1048]: info: touch_display: Volumio Kiosk started. Aug 30 17:35:40 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Aug 30 17:35:40 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:35:40 volumio volumio[1048]: info: Done. Aug 30 17:35:40 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Aug 30 17:35:40 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:35:40 volumio volumio[1048]: info: touch_display: X display number found: Aug 30 17:35:40 volumio startx[2003]: xauth: file /home/volumio/.Xauthority does not exist Aug 30 17:35:40 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:40 volumio startx[2003]: X.Org X Server 1.20.4 Aug 30 17:35:40 volumio startx[2003]: X Protocol Version 11, Revision 0 Aug 30 17:35:40 volumio startx[2003]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Aug 30 17:35:40 volumio startx[2003]: Current Operating System: Linux volumio 6.6.62-v7+ #1816 SMP Mon Nov 18 15:30:49 GMT 2024 armv7l Aug 30 17:35:40 volumio startx[2003]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=7f0c0e47-027d-47ad-bd0c-5159b014252e imgfile=/volumio_current.sqsh bootpart=UUID=10D8-1DF5 datapart=UUID=307f8728-204c-4df1-9dcc-f9fb9dec6fbe uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no cfg80211.ieee80211_regdom=JP Aug 30 17:35:40 volumio startx[2003]: Build Date: 04 April 2023 07:50:56AM Aug 30 17:35:40 volumio startx[2003]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Aug 30 17:35:40 volumio startx[2003]: Current version of pixman: 0.36.0 Aug 30 17:35:40 volumio startx[2003]: Before reporting problems, check http://wiki.x.org Aug 30 17:35:40 volumio startx[2003]: to make sure that you have the latest version. Aug 30 17:35:40 volumio startx[2003]: Markers: (--) probed, (**) from config file, (==) default setting, Aug 30 17:35:40 volumio startx[2003]: (++) from command line, (!!) notice, (II) informational, Aug 30 17:35:40 volumio startx[2003]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Aug 30 17:35:40 volumio startx[2003]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Aug 30 17:35:40 2025 Aug 30 17:35:40 volumio startx[2003]: (==) Using config directory: "/etc/X11/xorg.conf.d" Aug 30 17:35:40 volumio startx[2003]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Aug 30 17:35:40 volumio volumio[1048]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 30 17:35:40 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:41 volumio volumio[1048]: info: Enabling plugin touch_display Aug 30 17:35:41 volumio volumio[1048]: info: Loading plugin "touch_display"... Aug 30 17:35:41 volumio volumio[1048]: info: Applying required configuration parameters for plugin touch_display Aug 30 17:35:41 volumio volumio[1048]: info: PLUGIN START: touch_display Aug 30 17:35:41 volumio volumio[1048]: info: Loading i18n strings for locale ja Aug 30 17:35:41 volumio volumio[1048]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Aug 30 17:35:41 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 17:35:41 volumio sudo[2035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Aug 30 17:35:41 volumio sudo[2035]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:35:41 volumio volumio[1048]: info: touch_display: Backlight interface detected. Aug 30 17:35:41 volumio sudo[2037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Aug 30 17:35:41 volumio sudo[2037]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:35:41 volumio sudo[2043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/rpi_backlight/brightness Aug 30 17:35:41 volumio sudo[2039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 30 17:35:41 volumio sudo[2039]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:35:41 volumio sudo[2043]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:35:41 volumio sudo[2043]: pam_unix(sudo:session): session closed for user root Aug 30 17:35:41 volumio volumio[1048]: info: touch_display: Raspberry Pi Foundation touch screen detected. Aug 30 17:35:41 volumio volumio[1048]: info: touch_display: File permissions for backlight brightness control set. Aug 30 17:35:41 volumio sudo[2047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Aug 30 17:35:41 volumio sudo[2047]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:35:41 volumio sudo[2047]: pam_unix(sudo:session): session closed for user root Aug 30 17:35:41 volumio volumio[1048]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Aug 30 17:35:41 volumio sudo[2035]: pam_unix(sudo:session): session closed for user root Aug 30 17:35:41 volumio volumio[1048]: info: touch_display: systemctl stop getty@tty1.service succeeded. Aug 30 17:35:41 volumio systemd[1]: Reloading. Aug 30 17:35:41 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 30 17:35:42 volumio volumio[1048]: info: touch_display: Setting screensaver timeout to 120 seconds. Aug 30 17:35:42 volumio sudo[2078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Aug 30 17:35:42 volumio sudo[2078]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:35:42 volumio sudo[2078]: pam_unix(sudo:session): session closed for user root Aug 30 17:35:42 volumio volumio[1048]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Aug 30 17:35:42 volumio sudo[2091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service Aug 30 17:35:42 volumio sudo[2091]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:35:42 volumio systemd[1]: Reloading. Aug 30 17:35:42 volumio sudo[2037]: pam_unix(sudo:session): session closed for user root Aug 30 17:35:42 volumio volumio[1048]: info: touch_display: systemctl disable getty@tty1.service succeeded. Aug 30 17:35:42 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 17:35:42 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 17:35:42 volumio volumio[1048]: info: Discovery: Getting this device information Aug 30 17:35:42 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Aug 30 17:35:42 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:35:42 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 17:35:42 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 30 17:35:43 volumio sudo[2039]: pam_unix(sudo:session): session closed for user root Aug 30 17:35:43 volumio volumio[1048]: info: touch_display: systemctl daemon-reload succeeded. Aug 30 17:35:43 volumio systemd[1]: Stopping Volumio Kiosk... Aug 30 17:35:43 volumio startx[2003]: xinit: connection to X server lost Aug 30 17:35:43 volumio startx[2003]: [1B blob data] Aug 30 17:35:43 volumio sudo[2141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Aug 30 17:35:43 volumio sudo[2141]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:35:43 volumio startx[2003]: waiting for X server to shut down (II) Server terminated successfully (0). Closing log file. Aug 30 17:35:44 volumio startx[2003]: xinit: unexpected signal 15 Aug 30 17:35:44 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Aug 30 17:35:44 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Aug 30 17:35:44 volumio systemd[1]: Stopped Volumio Kiosk. Aug 30 17:35:44 volumio systemd[1]: Started Volumio Kiosk. Aug 30 17:35:44 volumio sudo[2091]: pam_unix(sudo:session): session closed for user root Aug 30 17:35:44 volumio sudo[2141]: pam_unix(sudo:session): session closed for user root Aug 30 17:35:44 volumio volumio[1048]: info: touch_display: systemctl restart volumio-kiosk.service succeeded. Aug 30 17:35:44 volumio volumio[1048]: info: touch_display: Volumio Kiosk restarted. Aug 30 17:35:44 volumio volumio[1048]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Aug 30 17:35:44 volumio volumio[1048]: info: touch_display: Volumio Kiosk started. Aug 30 17:35:44 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Aug 30 17:35:44 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:35:44 volumio volumio[1048]: info: Done. Aug 30 17:35:44 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Aug 30 17:35:44 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:35:44 volumio volumio[1048]: info: touch_display: X display number found: Aug 30 17:35:44 volumio volumio[1048]: info: touch_display: X display number found: Aug 30 17:35:44 volumio startx[2160]: X.Org X Server 1.20.4 Aug 30 17:35:44 volumio startx[2160]: X Protocol Version 11, Revision 0 Aug 30 17:35:44 volumio startx[2160]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Aug 30 17:35:44 volumio startx[2160]: Current Operating System: Linux volumio 6.6.62-v7+ #1816 SMP Mon Nov 18 15:30:49 GMT 2024 armv7l Aug 30 17:35:44 volumio startx[2160]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=7f0c0e47-027d-47ad-bd0c-5159b014252e imgfile=/volumio_current.sqsh bootpart=UUID=10D8-1DF5 datapart=UUID=307f8728-204c-4df1-9dcc-f9fb9dec6fbe uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no cfg80211.ieee80211_regdom=JP Aug 30 17:35:44 volumio startx[2160]: Build Date: 04 April 2023 07:50:56AM Aug 30 17:35:44 volumio startx[2160]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Aug 30 17:35:44 volumio startx[2160]: Current version of pixman: 0.36.0 Aug 30 17:35:44 volumio startx[2160]: Before reporting problems, check http://wiki.x.org Aug 30 17:35:44 volumio startx[2160]: to make sure that you have the latest version. Aug 30 17:35:44 volumio startx[2160]: Markers: (--) probed, (**) from config file, (==) default setting, Aug 30 17:35:44 volumio startx[2160]: (++) from command line, (!!) notice, (II) informational, Aug 30 17:35:44 volumio startx[2160]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Aug 30 17:35:44 volumio startx[2160]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Aug 30 17:35:44 2025 Aug 30 17:35:44 volumio startx[2160]: (==) Using config directory: "/etc/X11/xorg.conf.d" Aug 30 17:35:44 volumio startx[2160]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Aug 30 17:35:44 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:44 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:44 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:44 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:44 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:44 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:45 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:45 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:45 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:45 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:45 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:45 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:45 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:45 volumio volumio[1048]: info: Enabling plugin touch_display Aug 30 17:35:45 volumio volumio[1048]: info: Loading plugin "touch_display"... Aug 30 17:35:45 volumio volumio[1048]: info: Applying required configuration parameters for plugin touch_display Aug 30 17:35:45 volumio volumio[1048]: info: PLUGIN START: touch_display Aug 30 17:35:45 volumio volumio[1048]: info: Loading i18n strings for locale ja Aug 30 17:35:45 volumio volumio[1048]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Aug 30 17:35:45 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 17:35:45 volumio sudo[2271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Aug 30 17:35:45 volumio sudo[2271]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:35:45 volumio sudo[2273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Aug 30 17:35:45 volumio sudo[2273]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:35:45 volumio sudo[2277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 30 17:35:45 volumio systemd[1]: Reloading. Aug 30 17:35:45 volumio sudo[2277]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:35:45 volumio volumio[1048]: info: touch_display: Backlight interface detected. Aug 30 17:35:45 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:45 volumio volumio[1048]: info: touch_display: Raspberry Pi Foundation touch screen detected. Aug 30 17:35:45 volumio sudo[2291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Aug 30 17:35:45 volumio sudo[2291]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:35:45 volumio sudo[2291]: pam_unix(sudo:session): session closed for user root Aug 30 17:35:45 volumio sudo[2299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/rpi_backlight/brightness Aug 30 17:35:45 volumio sudo[2299]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:35:45 volumio sudo[2299]: pam_unix(sudo:session): session closed for user root Aug 30 17:35:45 volumio volumio[1048]: info: touch_display: File permissions for backlight brightness control set. Aug 30 17:35:45 volumio volumio[1048]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Aug 30 17:35:46 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 30 17:35:46 volumio sudo[2273]: pam_unix(sudo:session): session closed for user root Aug 30 17:35:46 volumio volumio[1048]: info: touch_display: systemctl disable getty@tty1.service succeeded. Aug 30 17:35:46 volumio sudo[2271]: pam_unix(sudo:session): session closed for user root Aug 30 17:35:46 volumio volumio[1048]: info: touch_display: systemctl stop getty@tty1.service succeeded. Aug 30 17:35:46 volumio systemd[1]: Reloading. Aug 30 17:35:47 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 30 17:35:48 volumio sudo[2277]: pam_unix(sudo:session): session closed for user root Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: systemctl daemon-reload succeeded. Aug 30 17:35:48 volumio sudo[2401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Aug 30 17:35:48 volumio sudo[2401]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio sudo[2401]: pam_unix(sudo:session): session closed for user root Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: Volumio Kiosk started. Aug 30 17:35:48 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Aug 30 17:35:48 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:35:48 volumio volumio[1048]: info: Done. Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: X display number found: 0 Aug 30 17:35:48 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Aug 30 17:35:48 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 30 17:35:48 volumio volumio[1048]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 30 17:35:48 volumio volumio[1048]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 17:35:48 volumio volumio[1048]: Error: connect EISCONN /tmp/.X11-unix/X0 Aug 30 17:35:48 volumio volumio[1048]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Aug 30 17:35:48 volumio volumio[1048]: errno: -106, Aug 30 17:35:48 volumio volumio[1048]: code: 'EISCONN', Aug 30 17:35:48 volumio volumio[1048]: syscall: 'connect', Aug 30 17:35:48 volumio volumio[1048]: address: '/tmp/.X11-unix/X0' Aug 30 17:35:48 volumio volumio[1048]: } Aug 30 17:35:48 volumio volumio[1048]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 17:35:49 volumio sudo[2444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-30 17:34 Aug 30 17:35:49 volumio sudo[2444]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"