-- Logs begin at Sun 2024-08-18 23:27:52 CST, end at Sun 2024-08-18 23:49:57 CST. -- Aug 18 23:48:10 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 18 23:48:10 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 18 23:48:14 volumio nmbd[1079]: [2024/08/18 23:48:14.710709, 0] ../source3/libsmb/nmblib.c:917(send_udp) Aug 18 23:48:14 volumio nmbd[1079]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Aug 18 23:48:14 volumio nmbd[1079]: [2024/08/18 23:48:14.710752, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Aug 18 23:48:14 volumio nmbd[1079]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Aug 18 23:48:14 volumio nmbd[1079]: [2024/08/18 23:48:14.710759, 0] ../source3/libsmb/nmblib.c:917(send_udp) Aug 18 23:48:14 volumio nmbd[1079]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Aug 18 23:48:14 volumio nmbd[1079]: [2024/08/18 23:48:14.710764, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Aug 18 23:48:14 volumio nmbd[1079]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Aug 18 23:48:14 volumio nmbd[1079]: [2024/08/18 23:48:14.710768, 0] ../source3/libsmb/nmblib.c:917(send_udp) Aug 18 23:48:14 volumio nmbd[1079]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Aug 18 23:48:14 volumio nmbd[1079]: [2024/08/18 23:48:14.710774, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Aug 18 23:48:14 volumio nmbd[1079]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Aug 18 23:48:14 volumio nmbd[1079]: [2024/08/18 23:48:14.710778, 0] ../source3/libsmb/nmblib.c:917(send_udp) Aug 18 23:48:14 volumio nmbd[1079]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Aug 18 23:48:14 volumio nmbd[1079]: [2024/08/18 23:48:14.710782, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Aug 18 23:48:14 volumio nmbd[1079]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Aug 18 23:48:14 volumio nmbd[1079]: [2024/08/18 23:48:14.710787, 0] ../source3/libsmb/nmblib.c:917(send_udp) Aug 18 23:48:14 volumio nmbd[1079]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Aug 18 23:48:14 volumio nmbd[1079]: [2024/08/18 23:48:14.710791, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Aug 18 23:48:14 volumio nmbd[1079]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Aug 18 23:48:14 volumio nmbd[1079]: [2024/08/18 23:48:14.710989, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 18 23:48:14 volumio nmbd[1079]: query_name_response: Multiple (2) responses received for a query on subnet 10.1.1.227 for name WORKGROUP<1d>. Aug 18 23:48:14 volumio nmbd[1079]: This response was from IP 10.1.1.252, reporting an IP address of 10.1.1.252. Aug 18 23:48:19 volumio nmbd[1079]: [2024/08/18 23:48:19.716595, 0] ../source3/libsmb/nmblib.c:917(send_udp) Aug 18 23:48:19 volumio nmbd[1079]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Aug 18 23:48:34 volumio ntpd[3772]: ntpd exiting on signal 15 (Terminated) Aug 18 23:48:34 volumio systemd[1]: Stopping Network Time Service... Aug 18 23:48:34 volumio ntpd[3772]: 111.230.189.174 local addr 10.1.1.227 -> Aug 18 23:48:34 volumio ntpd[3772]: 119.28.183.184 local addr 10.1.1.227 -> Aug 18 23:48:34 volumio ntpd[3772]: 193.182.111.14 local addr 10.1.1.227 -> Aug 18 23:48:34 volumio ntpd[3772]: 193.182.111.12 local addr 10.1.1.227 -> Aug 18 23:48:34 volumio ntpd[3772]: 202.112.31.197 local addr 10.1.1.227 -> Aug 18 23:48:34 volumio ntpd[3772]: 185.209.85.222 local addr 10.1.1.227 -> Aug 18 23:48:34 volumio ntpd[3772]: 78.46.102.180 local addr 10.1.1.227 -> Aug 18 23:48:34 volumio ntpd[3772]: 193.182.111.141 local addr 10.1.1.227 -> Aug 18 23:48:34 volumio ntpd[3772]: 162.159.200.123 local addr 10.1.1.227 -> Aug 18 23:48:34 volumio ntpd[3772]: 119.28.206.193 local addr 10.1.1.227 -> Aug 18 23:48:34 volumio ntpd[3772]: 84.16.67.12 local addr 10.1.1.227 -> Aug 18 23:48:34 volumio ntpd[3772]: 84.16.73.33 local addr 10.1.1.227 -> Aug 18 23:48:34 volumio systemd[1]: ntp.service: Succeeded. Aug 18 23:48:34 volumio systemd[1]: Stopped Network Time Service. Aug 18 23:48:34 volumio systemd[1]: Starting Network Time Service... Aug 18 23:48:34 volumio ntpd[4141]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 18 23:48:34 volumio ntpd[4141]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Aug 18 23:48:34 volumio systemd[1]: Started Network Time Service. Aug 18 23:48:34 volumio ntpd[4147]: proto: precision = 0.032 usec (-25) Aug 18 23:48:34 volumio ntpd[4147]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 18 23:48:34 volumio ntpd[4147]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 18 23:48:34 volumio ntpd[4147]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 600 days ago Aug 18 23:48:34 volumio ntpd[4147]: Listen and drop on 0 v6wildcard [::]:123 Aug 18 23:48:34 volumio ntpd[4147]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 18 23:48:34 volumio ntpd[4147]: Listen normally on 2 lo 127.0.0.1:123 Aug 18 23:48:34 volumio ntpd[4147]: Listen normally on 3 eth1 10.1.1.227:123 Aug 18 23:48:34 volumio ntpd[4147]: Listening on routing socket on fd #20 for interface updates Aug 18 23:48:34 volumio ntpd[4147]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 18 23:48:34 volumio ntpd[4147]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 18 23:48:35 volumio ntpd[4147]: Soliciting pool server 111.230.189.174 Aug 18 23:48:36 volumio ntpd[4147]: Soliciting pool server 193.182.111.12 Aug 18 23:48:36 volumio ntpd[4147]: Soliciting pool server 84.16.67.12 Aug 18 23:48:37 volumio ntpd[4147]: Soliciting pool server 78.46.102.180 Aug 18 23:48:37 volumio ntpd[4147]: Soliciting pool server 119.28.183.184 Aug 18 23:48:37 volumio ntpd[4147]: Soliciting pool server 193.182.111.141 Aug 18 23:48:38 volumio ntpd[4147]: Soliciting pool server 202.118.1.81 Aug 18 23:48:38 volumio ntpd[4147]: Soliciting pool server 162.159.200.123 Aug 18 23:48:38 volumio ntpd[4147]: Soliciting pool server 84.16.73.33 Aug 18 23:48:39 volumio volumio[1208]: info: CoreCommandRouter::volumioPause Aug 18 23:48:39 volumio volumio[1208]: info: CoreStateMachine::pause Aug 18 23:48:39 volumio volumio[1208]: info: CoreStateMachine::stPlaybackTimer Aug 18 23:48:39 volumio volumio[1208]: info: CoreStateMachine::servicePause Aug 18 23:48:39 volumio volumio[1208]: info: CorePlayQueue::getTrack 0 Aug 18 23:48:39 volumio volumio[1208]: info: CoreCommandRouter::servicePause Aug 18 23:48:39 volumio volumio[1208]: info: ControllerMpd::pause Aug 18 23:48:39 volumio volumio[1208]: verbose: ControllerMpd::sendMpdCommand pause Aug 18 23:48:39 volumio volumio[1208]: info: Aug 18 23:48:39 volumio volumio[1208]: ---------------------------- MPD announces state update: player Aug 18 23:48:39 volumio volumio[1208]: info: sendMpdCommand pause took 1 milliseconds Aug 18 23:48:39 volumio volumio[1208]: info: ControllerMpd::getState Aug 18 23:48:39 volumio volumio[1208]: verbose: ControllerMpd::sendMpdCommand status Aug 18 23:48:39 volumio volumio[1208]: info: sendMpdCommand status took 0 milliseconds Aug 18 23:48:39 volumio volumio[1208]: verbose: ControllerMpd::parseState Aug 18 23:48:39 volumio volumio[1208]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 23:48:39 volumio volumio[1208]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 18 23:48:39 volumio volumio[1208]: verbose: ControllerMpd::parseTrackInfo Aug 18 23:48:39 volumio volumio[1208]: info: ControllerMpd::pushState Aug 18 23:48:39 volumio volumio[1208]: info: CoreCommandRouter::servicePushState Aug 18 23:48:39 volumio volumio[1208]: info: CorePlayQueue::getTrack 0 Aug 18 23:48:39 volumio volumio[1208]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":44741,"duration":202,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"和平与爱","artist":"Beyond","album":"Best Collection SACD","uri":"NAS/TrueNAS/Beyond - SACD Best Collection/01 - Beyond - 和平与爱.dsf","trackType":"dsf"} Aug 18 23:48:39 volumio volumio[1208]: verbose: CURRENT POSITION 0 Aug 18 23:48:39 volumio volumio[1208]: info: CoreStateMachine::syncState stateService pause Aug 18 23:48:39 volumio volumio[1208]: info: CoreStateMachine::syncState currentStatus pause Aug 18 23:48:39 volumio volumio[1208]: info: CoreStateMachine::pushState Aug 18 23:48:39 volumio volumio[1208]: info: CorePlayQueue::getTrack 0 Aug 18 23:48:39 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 23:48:39 volumio volumio[1208]: info: CoreCommandRouter::volumioPushState Aug 18 23:48:39 volumio volumio[1208]: info: CoreStateMachine::stPlaybackTimer Aug 18 23:48:39 volumio volumio[1208]: info: ------------------------------ 4ms Aug 18 23:48:39 volumio ntpd[4147]: Soliciting pool server 2001:1600:3:6::123 Aug 18 23:48:43 volumio volumio[1208]: info: CoreCommandRouter::volumioGetState Aug 18 23:48:43 volumio volumio[1208]: info: CorePlayQueue::getTrack 0 Aug 18 23:48:44 volumio volumio[1208]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 23:48:44 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 18 23:48:44 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 18 23:48:44 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Aug 18 23:48:44 volumio volumio[1208]: info: Received Get System Version Aug 18 23:48:44 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 23:48:57 volumio volumio-remote-updater[3268]: No test mode Aug 18 23:48:57 volumio volumio-remote-updater[3268]: No alpha test mode Aug 18 23:48:58 volumio volumio[1208]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 18 23:48:58 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 18 23:48:59 volumio volumio[1208]: info: CoreCommandRouter::Close All Modals sent Aug 18 23:49:14 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , installToDisk Aug 18 23:49:14 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: networkfs , disableDeviceActions Aug 18 23:49:14 volumio volumio[1208]: info: Mount handler: device actions disabled Aug 18 23:49:14 volumio sudo[4164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/local/bin/x86Installer.sh /dev/nvme2n1 gpt 1 180 3800 /dev/nvme2n1p1 /dev/nvme2n1p2 /dev/nvme2n1p3 Aug 18 23:49:14 volumio sudo[4164]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 23:49:14 volumio volumio[1208]: info: Progress: 0 Aug 18 23:49:14 volumio volumio[1208]: info: Progress: 5 Aug 18 23:49:14 volumio volumio[1208]: info: Progress: 10 Aug 18 23:49:14 volumio volumio[1208]: info: Progress: 13 Aug 18 23:49:15 volumio volumio[1208]: info: Progress: 16 Aug 18 23:49:15 volumio volumio[1208]: info: Progress: 20 Aug 18 23:49:18 volumio volumio[1208]: info: Progress: 25 Aug 18 23:49:18 volumio volumio[1208]: info: Progress: 30 Aug 18 23:49:18 volumio volumio[1208]: info: Progress: 35 Aug 18 23:49:19 volumio volumio[1208]: info: Progress: 40 Aug 18 23:49:20 volumio volumio[1208]: info: Progress: 50 Aug 18 23:49:20 volumio kernel: FAT-fs (nvme2n1p1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Aug 18 23:49:20 volumio kernel: EXT4-fs (nvme2n1p2): mounted filesystem c0a4d169-8314-4e7f-88bb-9e65e6234122 r/w with ordered data mode. Quota mode: none. Aug 18 23:49:20 volumio volumio[1208]: info: Progress: 55 Aug 18 23:49:22 volumio volumio[1208]: info: Progress: 65 Aug 18 23:49:23 volumio nmbd[1079]: [2024/08/18 23:49:23.807494, 0] ../source3/libsmb/nmblib.c:917(send_udp) Aug 18 23:49:23 volumio nmbd[1079]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Aug 18 23:49:44 volumio volumio[1208]: info: Progress: 85 Aug 18 23:49:44 volumio volumio[1208]: info: Progress: 95 Aug 18 23:49:44 volumio volumio[1208]: info: Progress: 99 Aug 18 23:49:44 volumio systemd[1]: tmp-boot.mount: Succeeded. Aug 18 23:49:44 volumio systemd[1]: tmp-volumio.mount: Succeeded. Aug 18 23:49:45 volumio kernel: EXT4-fs (nvme2n1p2): unmounting filesystem c0a4d169-8314-4e7f-88bb-9e65e6234122. Aug 18 23:49:46 volumio sudo[4164]: pam_unix(sudo:session): session closed for user root Aug 18 23:49:46 volumio volumio[1208]: info: Successfully installed x86 factory copy to disk/dev/nvme2n1 Aug 18 23:49:46 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: networkfs , enableDeviceActions Aug 18 23:49:46 volumio volumio[1208]: info: Mount handler: device actions (re-)enabled Aug 18 23:49:55 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , installToDisk Aug 18 23:49:55 volumio volumio[1208]: info: CoreCommandRouter::executeOnPlugin: networkfs , disableDeviceActions Aug 18 23:49:55 volumio volumio[1208]: info: Mount handler: device actions disabled Aug 18 23:49:55 volumio sudo[4333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/local/bin/x86Installer.sh /dev/nvme1n1 gpt 1 180 3800 /dev/nvme1n1p1 /dev/nvme1n1p2 /dev/nvme1n1p3 Aug 18 23:49:55 volumio sudo[4333]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 23:49:55 volumio volumio[1208]: info: Progress: 0 Aug 18 23:49:55 volumio volumio[1208]: info: Progress: 0 Aug 18 23:49:55 volumio volumio[1208]: info: Progress: 5 Aug 18 23:49:55 volumio volumio[1208]: info: Progress: 5 Aug 18 23:49:55 volumio volumio[1208]: info: Progress: 10 Aug 18 23:49:55 volumio volumio[1208]: info: Progress: 10 Aug 18 23:49:55 volumio kernel: nvme1n1: Aug 18 23:49:55 volumio volumio[1208]: info: Progress: 13 Aug 18 23:49:55 volumio volumio[1208]: info: Progress: 13 Aug 18 23:49:55 volumio kernel: nvme1n1: p1 Aug 18 23:49:55 volumio volumio[1208]: info: Progress: 16 Aug 18 23:49:55 volumio volumio[1208]: info: Progress: 16 Aug 18 23:49:55 volumio kernel: nvme1n1: p1 p2 Aug 18 23:49:55 volumio volumio[1208]: info: Progress: 20 Aug 18 23:49:55 volumio volumio[1208]: info: Progress: 20 Aug 18 23:49:55 volumio kernel: nvme1n1: p1 p2 p3 Aug 18 23:49:55 volumio kernel: nvme1n1: p1 p2 p3 Aug 18 23:49:55 volumio kernel: nvme1n1: p1 p2 p3 Aug 18 23:49:55 volumio kernel: nvme1n1: p1 p2 p3 Aug 18 23:49:57 volumio volumio[1208]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 23:49:57 volumio volumio[1208]: Error: connect ETIMEDOUT 199.16.158.190:80 Aug 18 23:49:57 volumio volumio[1208]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Aug 18 23:49:57 volumio volumio[1208]: errno: -110, Aug 18 23:49:57 volumio volumio[1208]: code: 'ETIMEDOUT', Aug 18 23:49:57 volumio volumio[1208]: syscall: 'connect', Aug 18 23:49:57 volumio volumio[1208]: address: '199.16.158.190', Aug 18 23:49:57 volumio volumio[1208]: port: 80 Aug 18 23:49:57 volumio volumio[1208]: } Aug 18 23:49:57 volumio volumio[1208]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 23:49:57 volumio sudo[4407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-18 23:48 Aug 18 23:49:57 volumio sudo[4407]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"