-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sat 2023-01-07 19:16:37 UTC. -- Jan 07 19:15:02 volumio-loft sudo[909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.22/Music /mnt/NAS/VAULT Jan 07 19:15:02 volumio-loft sudo[909]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 07 19:15:02 volumio-loft sudo[909]: pam_unix(sudo:session): session closed for user root Jan 07 19:15:02 volumio-loft volumio[679]: info: Cannot mount NAS VAULT at system boot, trial number 4 ,retrying in 5 seconds Jan 07 19:15:02 volumio-loft kernel: CIFS: Attempting to mount //192.168.0.22/Music Jan 07 19:15:02 volumio-loft kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 07 19:15:02 volumio-loft kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jan 07 19:15:02 volumio-loft systemd[1]: systemd-fsckd.service: Succeeded. Jan 07 19:15:03 volumio-loft sh[397]: timed out Jan 07 19:15:03 volumio-loft dhcpcd[438]: timed out Jan 07 19:15:03 volumio-loft sh[397]: dhcpcd exited Jan 07 19:15:03 volumio-loft dhcpcd[438]: dhcpcd exited Jan 07 19:15:03 volumio-loft sh[397]: ifup: failed to bring up eth0 Jan 07 19:15:03 volumio-loft systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jan 07 19:15:03 volumio-loft systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jan 07 19:15:07 volumio-loft sudo[915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.22/Music /mnt/NAS/VAULT Jan 07 19:15:07 volumio-loft sudo[915]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 07 19:15:07 volumio-loft sudo[915]: pam_unix(sudo:session): session closed for user root Jan 07 19:15:07 volumio-loft kernel: CIFS: Attempting to mount //192.168.0.22/Music Jan 07 19:15:07 volumio-loft kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 07 19:15:07 volumio-loft kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jan 07 19:15:07 volumio-loft volumio[679]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jan 07 19:15:08 volumio-loft volumio[679]: error: MyVolumio Plugin failed to start in a timely fashion Jan 07 19:15:08 volumio-loft volumio[679]: info: BOOT COMPLETED Jan 07 19:15:08 volumio-loft volumio[679]: [Metrics] CommandRouter: 25s 408.23ms Jan 07 19:15:08 volumio-loft volumio[679]: info: CoreCommandRouter::volumiosetStartupVolume Jan 07 19:15:08 volumio-loft volumio[679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 07 19:15:08 volumio-loft volumio[679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 07 19:15:08 volumio-loft volumio[679]: info: CoreCommandRouter::Close All Modals sent Jan 07 19:15:08 volumio-loft volumio[679]: info: CoreCommandRouter::Close All Modals sent Jan 07 19:15:08 volumio-loft volumio[679]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.396&uuid=d1878d41c498f09403c83ffadde64208" http://updates.volumio.org/downloader-v1/track-device Jan 07 19:15:08 volumio-loft volumio[679]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 07 19:15:08 volumio-loft volumio[679]: Dload Upload Total Spent Left Speed Jan 07 19:15:08 volumio-loft volumio[679]: [132B blob data] Jan 07 19:15:08 volumio-loft volumio[679]: retrying in 5 seconds, trial 1 Jan 07 19:15:08 volumio-loft volumio[679]: info: Volumio Calling Home Jan 07 19:15:09 volumio-loft volumio[679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 07 19:15:09 volumio-loft volumio[679]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 07 19:15:09 volumio-loft volumio[679]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 07 19:15:12 volumio-loft systemd[1]: systemd-hostnamed.service: Succeeded. Jan 07 19:15:17 volumio-loft volumio[679]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 07 19:15:17 volumio-loft volumio[679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 07 19:15:18 volumio-loft volumio[679]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.396&uuid=d1878d41c498f09403c83ffadde64208" http://updates.volumio.org/downloader-v1/track-device Jan 07 19:15:18 volumio-loft volumio[679]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 07 19:15:18 volumio-loft volumio[679]: Dload Upload Total Spent Left Speed Jan 07 19:15:18 volumio-loft volumio[679]: [132B blob data] Jan 07 19:15:18 volumio-loft volumio[679]: retrying in 5 seconds, trial 2 Jan 07 19:15:18 volumio-loft volumio[679]: info: Volumio Calling Home Jan 07 19:15:44 volumio-loft ntpd[652]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 07 19:15:45 volumio-loft ntpd[652]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 07 19:15:46 volumio-loft ntpd[652]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 07 19:15:48 volumio-loft ntpd[652]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 07 19:15:55 volumio-loft systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 07 19:15:55 volumio-loft systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 07 19:16:07 volumio-loft systemd[1]: nmbd.service: Start operation timed out. Terminating. Jan 07 19:16:07 volumio-loft systemd[1]: nmbd.service: Main process exited, code=killed, status=15/TERM Jan 07 19:16:07 volumio-loft systemd[1]: nmbd.service: Failed with result 'timeout'. Jan 07 19:16:07 volumio-loft systemd[1]: Failed to start Samba NMB Daemon. Jan 07 19:16:07 volumio-loft systemd[1]: Starting Samba Winbind Daemon... Jan 07 19:16:07 volumio-loft winbindd[933]: [2023/01/07 19:16:07.226457, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 07 19:16:07 volumio-loft winbindd[933]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 07 19:16:08 volumio-loft winbindd[933]: [2023/01/07 19:16:08.156711, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 07 19:16:08 volumio-loft winbindd[933]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 07 19:16:08 volumio-loft systemd[1]: Started Samba Winbind Daemon. Jan 07 19:16:08 volumio-loft systemd[1]: Starting Samba SMB Daemon... Jan 07 19:16:10 volumio-loft kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Jan 07 19:16:10 volumio-loft systemd[1]: Started Samba SMB Daemon. Jan 07 19:16:10 volumio-loft systemd[1]: Reached target Multi-User System. Jan 07 19:16:10 volumio-loft systemd[1]: Reached target Graphical Interface. Jan 07 19:16:10 volumio-loft smbd[937]: [2023/01/07 19:16:10.376879, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 07 19:16:10 volumio-loft systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 07 19:16:10 volumio-loft smbd[937]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 07 19:16:10 volumio-loft systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 07 19:16:10 volumio-loft systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 07 19:16:10 volumio-loft systemd[1]: Startup finished in 13.686s (kernel) + 1min 45.259s (userspace) = 1min 58.946s. Jan 07 19:16:10 volumio-loft ifplugd(eth0)[647]: Link beat detected. Jan 07 19:16:10 volumio-loft ifplugd(eth0)[647]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jan 07 19:16:10 volumio-loft dhcpcd[965]: DUID 00:01:00:01:2a:5e:eb:6d:dc:a6:32:ba:51:14 Jan 07 19:16:10 volumio-loft dhcpcd[965]: eth0: IAID 32:ba:51:14 Jan 07 19:16:10 volumio-loft dhcpcd[965]: eth0: adding address fe80::dea6:32ff:feba:5114 Jan 07 19:16:10 volumio-loft dhcpcd[965]: ipv6_addaddr1: Permission denied Jan 07 19:16:10 volumio-loft ifplugd(eth0)[647]: client: DUID 00:01:00:01:2a:5e:eb:6d:dc:a6:32:ba:51:14 Jan 07 19:16:10 volumio-loft ifplugd(eth0)[647]: client: eth0: IAID 32:ba:51:14 Jan 07 19:16:10 volumio-loft ifplugd(eth0)[647]: client: eth0: adding address fe80::dea6:32ff:feba:5114 Jan 07 19:16:10 volumio-loft ifplugd(eth0)[647]: client: ipv6_addaddr1: Permission denied Jan 07 19:16:10 volumio-loft dhcpcd[965]: eth0: soliciting an IPv6 router Jan 07 19:16:10 volumio-loft ifplugd(eth0)[647]: client: eth0: soliciting an IPv6 router Jan 07 19:16:11 volumio-loft dhcpcd[965]: eth0: rebinding lease of 192.168.0.33 Jan 07 19:16:11 volumio-loft ifplugd(eth0)[647]: client: eth0: rebinding lease of 192.168.0.33 Jan 07 19:16:11 volumio-loft dhcpcd[965]: eth0: probing address 192.168.0.33/24 Jan 07 19:16:11 volumio-loft ifplugd(eth0)[647]: client: eth0: probing address 192.168.0.33/24 Jan 07 19:16:16 volumio-loft dhcpcd[965]: eth0: leased 192.168.0.33 for 86400 seconds Jan 07 19:16:16 volumio-loft ifplugd(eth0)[647]: client: eth0: leased 192.168.0.33 for 86400 seconds Jan 07 19:16:16 volumio-loft dhcpcd[965]: eth0: adding route to 192.168.0.0/24 Jan 07 19:16:16 volumio-loft dhcpcd[965]: eth0: adding default route via 192.168.0.1 Jan 07 19:16:16 volumio-loft ifplugd(eth0)[647]: client: eth0: adding route to 192.168.0.0/24 Jan 07 19:16:16 volumio-loft ifplugd(eth0)[647]: client: eth0: adding default route via 192.168.0.1 Jan 07 19:16:16 volumio-loft avahi-daemon[529]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.33. Jan 07 19:16:16 volumio-loft avahi-daemon[529]: New relevant interface eth0.IPv4 for mDNS. Jan 07 19:16:16 volumio-loft avahi-daemon[529]: Registering new address record for 192.168.0.33 on eth0.IPv4. Jan 07 19:16:16 volumio-loft dhcpcd[965]: forked to background, child pid 989 Jan 07 19:16:16 volumio-loft ifplugd(eth0)[647]: client: forked to background, child pid 989 Jan 07 19:16:17 volumio-loft volumio[679]: info: Discovery: adding 6fab5f22-eccd-4435-a861-58c043d67d5c Jan 07 19:16:17 volumio-loft volumio[679]: info: Discovery: Found device Volumio-loft Jan 07 19:16:17 volumio-loft volumio[679]: info: CoreCommandRouter::volumioGetState Jan 07 19:16:17 volumio-loft volumio[679]: info: CorePlayQueue::getTrack 0 Jan 07 19:16:17 volumio-loft ifplugd(eth0)[647]: Program executed successfully. Jan 07 19:16:17 volumio-loft ntpd[652]: Listen normally on 3 eth0 192.168.0.33:123 Jan 07 19:16:17 volumio-loft ntpd[652]: new interface(s) found: waking up resolver Jan 07 19:16:27 volumio-loft volumio[679]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 07 19:16:27 volumio-loft volumio[679]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 07 19:16:27 volumio-loft volumio[679]: info: Discovery: Getting this device information Jan 07 19:16:27 volumio-loft volumio[679]: info: CoreCommandRouter::volumioGetState Jan 07 19:16:27 volumio-loft volumio[679]: info: CorePlayQueue::getTrack 0 Jan 07 19:16:27 volumio-loft volumio[679]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 07 19:16:28 volumio-loft volumio[679]: verbose: New Socket.io Connection to 192.168.0.33 from 192.168.0.17 UA: okhttp/4.9.2 Total Clients: 3 Jan 07 19:16:28 volumio-loft volumio[679]: info: CoreCommandRouter::volumioGetState Jan 07 19:16:28 volumio-loft volumio[679]: info: CorePlayQueue::getTrack 0 Jan 07 19:16:28 volumio-loft volumio[679]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 07 19:16:28 volumio-loft volumio[679]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 07 19:16:34 volumio-loft volumio[679]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 07 19:16:34 volumio-loft volumio[679]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 07 19:16:34 volumio-loft volumio[679]: info: Discovery: Getting this device information Jan 07 19:16:34 volumio-loft volumio[679]: info: CoreCommandRouter::volumioGetState Jan 07 19:16:34 volumio-loft volumio[679]: info: CorePlayQueue::getTrack 0 Jan 07 19:16:34 volumio-loft volumio[679]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 07 19:16:35 volumio-loft volumio[679]: verbose: New Socket.io Connection to 192.168.0.33 from 192.168.0.17 UA: okhttp/4.9.2 Total Clients: 3 Jan 07 19:16:35 volumio-loft volumio[679]: info: CoreCommandRouter::volumioGetState Jan 07 19:16:35 volumio-loft volumio[679]: info: CorePlayQueue::getTrack 0 Jan 07 19:16:35 volumio-loft volumio[679]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 07 19:16:35 volumio-loft volumio[679]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 07 19:16:36 volumio-loft volumio[679]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 07 19:16:36 volumio-loft volumio[679]: Error: certificate is not yet valid Jan 07 19:16:36 volumio-loft volumio[679]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Jan 07 19:16:36 volumio-loft volumio[679]: at TLSSocket.emit (events.js:315:20) Jan 07 19:16:36 volumio-loft volumio[679]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Jan 07 19:16:36 volumio-loft volumio[679]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Jan 07 19:16:36 volumio-loft volumio[679]: code: 'CERT_NOT_YET_VALID' Jan 07 19:16:36 volumio-loft volumio[679]: } Jan 07 19:16:36 volumio-loft volumio[679]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 07 19:16:37 volumio-loft sudo[1023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-01-07 19:15 Jan 07 19:16:37 volumio-loft sudo[1023]: 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="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="8e6823482d6af342172ec9fdc1c15b0dd02653d2" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 04 Dec 2022 08:41:29 PM CET" VOLUMIO_VERSION="3.396" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d460c75d04818d0f55c67627fa362668"