-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sat 2024-04-20 08:35:31 UTC. -- Apr 20 08:34:00 volumiohall volumio[896]: info: Discovery: adding be4221ea-6182-4cf9-afa1-3c11a9ddc094 Apr 20 08:34:00 volumiohall volumio[896]: info: mDNS: Found device TEST Apr 20 08:34:00 volumiohall volumio[896]: info: Discovery: adding 24c49d9b-c855-416f-ba17-ec4e95f44410 Apr 20 08:34:00 volumiohall volumio[896]: info: mDNS: Found device Volumio_Hall Apr 20 08:34:00 volumiohall volumio[896]: info: CoreCommandRouter::volumioGetState Apr 20 08:34:00 volumiohall volumio[896]: info: CorePlayQueue::getTrack 0 Apr 20 08:34:02 volumiohall volumio[896]: error: MPD error: The expression evaluated to a falsy value: Apr 20 08:34:02 volumiohall volumio[896]: assert.ok(self.idling) Apr 20 08:34:02 volumiohall volumio[896]: error: updateQueue error: null Apr 20 08:34:03 volumiohall volumio[896]: info: Volumio called home Apr 20 08:34:05 volumiohall sudo[1045]: volumio : unable to resolve host volumiohall Apr 20 08:34:05 volumiohall sudo[1045]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 08:34:05 volumiohall sudo[1047]: volumio : unable to resolve host volumiohall Apr 20 08:34:05 volumiohall sudo[1047]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 08:34:05 volumiohall sudo[1045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 08:34:05 volumiohall sudo[1045]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 08:34:05 volumiohall sudo[1047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 08:34:05 volumiohall sudo[1047]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 08:34:05 volumiohall sudo[1045]: pam_unix(sudo:session): session closed for user root Apr 20 08:34:05 volumiohall sudo[1047]: pam_unix(sudo:session): session closed for user root Apr 20 08:34:05 volumiohall sudo[1049]: volumio : unable to resolve host volumiohall Apr 20 08:34:05 volumiohall sudo[1049]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 08:34:05 volumiohall sudo[1049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 20 08:34:05 volumiohall sudo[1049]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 08:34:05 volumiohall systemd[1]: Started UPnP Renderer front-end to MPD. Apr 20 08:34:05 volumiohall sudo[1049]: pam_unix(sudo:session): session closed for user root Apr 20 08:34:05 volumiohall volumio[896]: info: Upmpdcli Daemon Started Apr 20 08:34:05 volumiohall volumio[1118]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 20 08:34:07 volumiohall volumio[896]: error: updateQueue error: null Apr 20 08:34:07 volumiohall sudo[1086]: volumio : unable to resolve host volumiohall Apr 20 08:34:07 volumiohall sudo[1086]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 08:34:07 volumiohall sudo[1086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 08:34:07 volumiohall sudo[1086]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 08:34:07 volumiohall sudo[1086]: pam_unix(sudo:session): session closed for user root Apr 20 08:34:07 volumiohall sudo[1088]: volumio : unable to resolve host volumiohall Apr 20 08:34:07 volumiohall sudo[1088]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 08:34:07 volumiohall volumio[896]: info: MPD Permissions set Apr 20 08:34:07 volumiohall sudo[1088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 08:34:07 volumiohall sudo[1088]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 08:34:07 volumiohall systemd[1]: Stopping Music Player Daemon... Apr 20 08:34:07 volumiohall systemd[1]: mpd.service: Succeeded. Apr 20 08:34:07 volumiohall systemd[1]: Stopped Music Player Daemon. Apr 20 08:34:07 volumiohall systemd[1]: Starting Music Player Daemon... Apr 20 08:34:08 volumiohall sudo[1102]: volumio : unable to resolve host volumiohall Apr 20 08:34:08 volumiohall sudo[1102]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 08:34:08 volumiohall sudo[1102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 20 08:34:08 volumiohall sudo[1102]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 08:34:08 volumiohall systemd[1]: Started go-librespot Daemon. Apr 20 08:34:08 volumiohall sudo[1108]: volumio : unable to resolve host volumiohall Apr 20 08:34:08 volumiohall sudo[1108]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 08:34:08 volumiohall sudo[1102]: pam_unix(sudo:session): session closed for user root Apr 20 08:34:08 volumiohall sudo[1110]: volumio : unable to resolve host volumiohall Apr 20 08:34:08 volumiohall go-librespot[1140]: Librespot-go daemon starting... Apr 20 08:34:08 volumiohall sudo[1110]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 08:34:08 volumiohall sudo[1108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 08:34:08 volumiohall sudo[1108]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 08:34:08 volumiohall sudo[1110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 08:34:08 volumiohall sudo[1110]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 08:34:08 volumiohall sudo[1112]: volumio : unable to resolve host volumiohall Apr 20 08:34:08 volumiohall sudo[1112]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 08:34:08 volumiohall sudo[1112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 08:34:08 volumiohall sudo[1112]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 08:34:08 volumiohall systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 20 08:34:08 volumiohall systemd[1]: shairport-sync.service: Succeeded. Apr 20 08:34:08 volumiohall systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 20 08:34:08 volumiohall systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 20 08:34:08 volumiohall sudo[1108]: pam_unix(sudo:session): session closed for user root Apr 20 08:34:08 volumiohall volumio[896]: info: Shairport-Sync Started Apr 20 08:34:08 volumiohall sudo[1110]: pam_unix(sudo:session): session closed for user root Apr 20 08:34:08 volumiohall sudo[1112]: pam_unix(sudo:session): session closed for user root Apr 20 08:34:08 volumiohall volumio[896]: Error adding Membership: Error: addMembership EINVAL Apr 20 08:34:08 volumiohall volumio[896]: info: Shairport-Sync Started Apr 20 08:34:08 volumiohall volumio[896]: info: Shairport-Sync Started Apr 20 08:34:08 volumiohall go-librespot[1140]: time="2024-04-20T08:34:08Z" level=info msg="generated new device id: 550f666d22f20c30a6a628a2e070b69422b167f7" Apr 20 08:34:08 volumiohall go-librespot[1140]: time="2024-04-20T08:34:08Z" level=debug msg="stored credentials found for 315ataumopucfkq3gm2h7qfphqna" Apr 20 08:34:08 volumiohall mpd[1138]: Apr 20 08:34 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 20 08:34:08 volumiohall mpd[1138]: Apr 20 08:34 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 20 08:34:08 volumiohall mpd[1138]: Apr 20 08:34 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 20 08:34:08 volumiohall systemd[1]: Started Music Player Daemon. Apr 20 08:34:08 volumiohall sudo[1088]: pam_unix(sudo:session): session closed for user root Apr 20 08:34:09 volumiohall volumio[896]: error: updateQueue error: null Apr 20 08:34:10 volumiohall volumio[896]: info: Discovery: this is already registered, 24c49d9b-c855-416f-ba17-ec4e95f44410 Apr 20 08:34:10 volumiohall volumio[896]: info: mDNS: Found device Volumio_Hall Apr 20 08:34:10 volumiohall volumio[896]: info: CoreCommandRouter::volumioGetState Apr 20 08:34:10 volumiohall volumio[896]: info: CorePlayQueue::getTrack 0 Apr 20 08:34:10 volumiohall volumio[896]: info: Discovery: adding 8799fe24-80ee-4583-a299-67e71dad7620 Apr 20 08:34:10 volumiohall volumio[896]: info: mDNS: Found device Volumio_Salle01 Apr 20 08:34:11 volumiohall volumio[896]: info: go-librespot daemon successfully initialized Apr 20 08:34:12 volumiohall sudo[1114]: volumio : unable to resolve host volumiohall Apr 20 08:34:12 volumiohall sudo[1114]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 08:34:12 volumiohall sudo[1114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.161.59.240/\\DESKTOP-0T8GMDB\Volumnio /mnt/NAS/PC_Hall Apr 20 08:34:12 volumiohall sudo[1114]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 08:34:12 volumiohall kernel: CIFS: Attempting to mount //10.161.59.240/\\DESKTOP-0T8GMDB\Volumnio Apr 20 08:34:12 volumiohall kernel: CIFS: Status code returned 0xc0000022 STATUS_ACCESS_DENIED Apr 20 08:34:12 volumiohall kernel: CIFS: VFS: \\10.161.59.240 Send error in SessSetup = -13 Apr 20 08:34:12 volumiohall kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Apr 20 08:34:12 volumiohall sudo[1114]: pam_unix(sudo:session): session closed for user root Apr 20 08:34:12 volumiohall volumio[896]: info: Cannot mount NAS PC_Hall at system boot, trial number 2 ,retrying in 5 seconds Apr 20 08:34:13 volumiohall go-librespot[1140]: time="2024-04-20T08:34:13Z" level=debug msg="obtained new client token: AAD075EQ9zFGF4ntr4V9IwWmDxBkQoijyztLG7WtDAmvrqM9/sCxfjbvU23tXX7TCXZUmj7y0R0L6C1SMlLG4TyH6CIP1/LeLTHIlOYPI90J6ijFmaghpjnaBzVZB7NjlgUfj+vnvVuHyawKfVeV8kTQ3UFGYr7J7bb1upm9Nac5KjZtuxgN4rxhogyt7s4qkTRP/0bQr4hdWXcvC3cTorh4OZor1Z0xhonqxIwhfSOr3aRs8jELor86QXzgwtE=" Apr 20 08:34:14 volumiohall volumio[896]: info: Initializing connection to go-librespot Websocket Apr 20 08:34:15 volumiohall volumio[896]: verbose: New Socket.io Connection to 10.161.59.248:3000 from 10.161.59.48 UA: node-XMLHttpRequest Total Clients: 2 Apr 20 08:34:15 volumiohall volumio[896]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Apr 20 08:34:15 volumiohall volumio[896]: info: CoreCommandRouter::volumioGetState Apr 20 08:34:15 volumiohall volumio[896]: info: CorePlayQueue::getTrack 0 Apr 20 08:34:17 volumiohall volumio[896]: error: MyVolumio Plugin failed to start in a timely fashion Apr 20 08:34:17 volumiohall volumio[896]: info: BOOT COMPLETED Apr 20 08:34:17 volumiohall volumio[896]: [Metrics] CommandRouter: 35s 870.52ms Apr 20 08:34:17 volumiohall volumio[896]: info: CoreCommandRouter::volumiosetStartupVolume Apr 20 08:34:17 volumiohall volumio[896]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 08:34:17 volumiohall volumio[896]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 08:34:17 volumiohall volumio[896]: info: CoreCommandRouter::Close All Modals sent Apr 20 08:34:17 volumiohall volumio[896]: info: CoreCommandRouter::Close All Modals sent Apr 20 08:34:18 volumiohall volumio[896]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 20 08:34:18 volumiohall volumio[896]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 20 08:34:19 volumiohall volumio[896]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 20 08:34:19 volumiohall go-librespot[1140]: time="2024-04-20T08:34:19Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Apr 20 08:34:20 volumiohall volumio[896]: info: Discovery: this is already registered, be4221ea-6182-4cf9-afa1-3c11a9ddc094 Apr 20 08:34:20 volumiohall volumio[896]: info: mDNS: Found device TEST Apr 20 08:34:20 volumiohall volumio[896]: info: CoreCommandRouter::volumioGetState Apr 20 08:34:20 volumiohall volumio[896]: info: CorePlayQueue::getTrack 0 Apr 20 08:34:20 volumiohall volumio[896]: info: Discovery: this is already registered, be4221ea-6182-4cf9-afa1-3c11a9ddc094 Apr 20 08:34:20 volumiohall volumio[896]: info: mDNS: Found device TEST Apr 20 08:34:20 volumiohall volumio[896]: info: CoreCommandRouter::volumioGetState Apr 20 08:34:20 volumiohall volumio[896]: info: CorePlayQueue::getTrack 0 Apr 20 08:34:22 volumiohall volumio[1118]: ........................................................................................................................++++ Apr 20 08:34:24 volumiohall go-librespot[1140]: time="2024-04-20T08:34:24Z" level=debug msg="completed keyexchange" Apr 20 08:34:24 volumiohall go-librespot[1140]: time="2024-04-20T08:34:24Z" level=debug msg="completed challenge" Apr 20 08:34:24 volumiohall go-librespot[1140]: time="2024-04-20T08:34:24Z" level=debug msg="authenticated as 315ataumopucfkq3gm2h7qfphqna" Apr 20 08:34:25 volumiohall volumio[896]: verbose: New Socket.io Connection to 10.161.59.248:3000 from 10.161.59.18 UA: node-XMLHttpRequest Total Clients: 3 Apr 20 08:34:25 volumiohall volumio[896]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Apr 20 08:34:25 volumiohall volumio[896]: info: CoreCommandRouter::volumioGetState Apr 20 08:34:25 volumiohall volumio[896]: info: CorePlayQueue::getTrack 0 Apr 20 08:34:27 volumiohall sudo[1161]: volumio : unable to resolve host volumiohall Apr 20 08:34:27 volumiohall sudo[1161]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 08:34:27 volumiohall sudo[1161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.161.59.240/\\DESKTOP-0T8GMDB\Volumnio /mnt/NAS/PC_Hall Apr 20 08:34:27 volumiohall sudo[1161]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 08:34:27 volumiohall sudo[1161]: pam_unix(sudo:session): session closed for user root Apr 20 08:34:27 volumiohall kernel: CIFS: Attempting to mount //10.161.59.240/\\DESKTOP-0T8GMDB\Volumnio Apr 20 08:34:27 volumiohall kernel: CIFS: Status code returned 0xc0000022 STATUS_ACCESS_DENIED Apr 20 08:34:27 volumiohall kernel: CIFS: VFS: \\10.161.59.240 Send error in SessSetup = -13 Apr 20 08:34:27 volumiohall kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Apr 20 08:34:27 volumiohall volumio[896]: info: Cannot mount NAS PC_Hall at system boot, trial number 3 ,retrying in 5 seconds Apr 20 08:34:27 volumiohall volumio[896]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 08:34:27 volumiohall volumio[896]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 08:34:29 volumiohall go-librespot[1140]: time="2024-04-20T08:34:29Z" level=debug msg="authenticated as 315ataumopucfkq3gm2h7qfphqna" Apr 20 08:34:29 volumiohall go-librespot[1140]: time="2024-04-20T08:34:29Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 20 08:34:29 volumiohall go-librespot[1140]: time="2024-04-20T08:34:29Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Apr 20 08:34:30 volumiohall volumio[896]: info: Discovery: this is already registered, be4221ea-6182-4cf9-afa1-3c11a9ddc094 Apr 20 08:34:30 volumiohall volumio[896]: info: mDNS: Found device TEST Apr 20 08:34:30 volumiohall volumio[896]: info: CoreCommandRouter::volumioGetState Apr 20 08:34:30 volumiohall volumio[896]: info: CorePlayQueue::getTrack 0 Apr 20 08:34:30 volumiohall volumio[896]: info: Discovery: this is already registered, be4221ea-6182-4cf9-afa1-3c11a9ddc094 Apr 20 08:34:30 volumiohall volumio[896]: info: mDNS: Found device TEST Apr 20 08:34:30 volumiohall volumio[896]: info: CoreCommandRouter::volumioGetState Apr 20 08:34:30 volumiohall volumio[896]: info: CorePlayQueue::getTrack 0 Apr 20 08:34:34 volumiohall volumio[1118]: .......................................................................................++++ Apr 20 08:34:34 volumiohall volumio[1118]: e is 65537 (0x010001) Apr 20 08:34:34 volumiohall volumio[1118]: writing RSA key Apr 20 08:34:35 volumiohall go-librespot[1140]: time="2024-04-20T08:34:35Z" level=debug msg="dealer connection opened" Apr 20 08:34:35 volumiohall go-librespot[1140]: time="2024-04-20T08:34:35Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Apr 20 08:34:35 volumiohall go-librespot[1140]: time="2024-04-20T08:34:35Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Apr 20 08:34:35 volumiohall go-librespot[1140]: time="2024-04-20T08:34:35Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Apr 20 08:34:35 volumiohall go-librespot[1140]: time="2024-04-20T08:34:35Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Apr 20 08:34:35 volumiohall go-librespot[1140]: time="2024-04-20T08:34:35Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513" Apr 20 08:34:35 volumiohall go-librespot[1140]: time="2024-04-20T08:34:35Z" level=debug msg="received connection id: YmVlNjRjYTYtZmU1ZC00MzZkLTk5ZGYtMGZlNDI3MjM5NjU4K2RlYWxlcit0Y3A6Ly9nZXcxLWRlYWxlci1hLXM4dHouZ2V3MS5zcG90aWZ5Lm5ldDo1NzAwKzdEMUVBRDZGNTFBMEQzN0I0NzU4MkUwRjlENEIxMkU5OUU3MEI2MjVGM0Q5RDhFODY3QzZGRjQ4ODcxMENBRUM=" Apr 20 08:34:40 volumiohall go-librespot[1140]: time="2024-04-20T08:34:40Z" level=debug msg="put connect state because NEW_DEVICE" Apr 20 08:34:40 volumiohall volumio[896]: info: Discovery: this is already registered, be4221ea-6182-4cf9-afa1-3c11a9ddc094 Apr 20 08:34:40 volumiohall volumio[896]: info: mDNS: Found device TEST Apr 20 08:34:40 volumiohall volumio[896]: info: CoreCommandRouter::volumioGetState Apr 20 08:34:40 volumiohall volumio[896]: info: CorePlayQueue::getTrack 0 Apr 20 08:34:40 volumiohall volumio[896]: info: Discovery: this is already registered, be4221ea-6182-4cf9-afa1-3c11a9ddc094 Apr 20 08:34:40 volumiohall volumio[896]: info: mDNS: Found device TEST Apr 20 08:34:40 volumiohall volumio[896]: info: CoreCommandRouter::volumioGetState Apr 20 08:34:40 volumiohall volumio[896]: info: CorePlayQueue::getTrack 0 Apr 20 08:34:42 volumiohall sudo[1173]: volumio : unable to resolve host volumiohall Apr 20 08:34:42 volumiohall sudo[1173]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 08:34:42 volumiohall sudo[1173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.161.59.240/\\DESKTOP-0T8GMDB\Volumnio /mnt/NAS/PC_Hall Apr 20 08:34:42 volumiohall sudo[1173]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 08:34:42 volumiohall kernel: CIFS: Attempting to mount //10.161.59.240/\\DESKTOP-0T8GMDB\Volumnio Apr 20 08:34:42 volumiohall kernel: CIFS: Status code returned 0xc0000022 STATUS_ACCESS_DENIED Apr 20 08:34:42 volumiohall kernel: CIFS: VFS: \\10.161.59.240 Send error in SessSetup = -13 Apr 20 08:34:42 volumiohall kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Apr 20 08:34:42 volumiohall sudo[1173]: pam_unix(sudo:session): session closed for user root Apr 20 08:34:42 volumiohall volumio[896]: info: Cannot mount NAS PC_Hall at system boot, trial number 4 ,retrying in 5 seconds Apr 20 08:34:45 volumiohall volumio[896]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Apr 20 08:34:45 volumiohall volumio[896]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Apr 20 08:34:45 volumiohall volumio[896]: SPOTIFY: BQBA1OgmvhYBRLucyCiw3T_uRLOlXGM36L1b9ADO2OI2a5bgnIyZMdzOAJauziPCgn5UFidkigl8GWiqwVHRTRyxfmGlWYC9KKlGjlFR1N3Tg1YpFyy0vGWUS2jNRUOZLVgGA9_v93QqOqn-PxKXIDayPi9piRur63KpYD6S9gyQGOiXPUeFocB12BC2etAu9iuw6fw8ohkrihsdZbKIDteJ44FlnqJg-bgsqGaJt53DR55nPtIZYP4Q4xhgAhmfLjPOz_rIxdA Apr 20 08:34:45 volumiohall volumio[896]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Apr 20 08:34:45 volumiohall volumio[896]: info: New Spotify access token = BQBA1OgmvhYBRLucyCiw3T_uRLOlXGM36L1b9ADO2OI2a5bgnIyZMdzOAJauziPCgn5UFidkigl8GWiqwVHRTRyxfmGlWYC9KKlGjlFR1N3Tg1YpFyy0vGWUS2jNRUOZLVgGA9_v93QqOqn-PxKXIDayPi9piRur63KpYD6S9gyQGOiXPUeFocB12BC2etAu9iuw6fw8ohkrihsdZbKIDteJ44FlnqJg-bgsqGaJt53DR55nPtIZYP4Q4xhgAhmfLjPOz_rIxdA Apr 20 08:34:45 volumiohall volumio[896]: info: Spotify credentials grant success - running version from March 24, 2019 Apr 20 08:34:48 volumiohall sudo[1189]: volumio : unable to resolve host volumiohall Apr 20 08:34:48 volumiohall sudo[1189]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 08:34:48 volumiohall sudo[1189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 08:34:48 volumiohall sudo[1189]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 08:34:48 volumiohall sudo[1191]: volumio : unable to resolve host volumiohall Apr 20 08:34:48 volumiohall sudo[1191]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 08:34:48 volumiohall sudo[1189]: pam_unix(sudo:session): session closed for user root Apr 20 08:34:48 volumiohall sudo[1191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 08:34:48 volumiohall sudo[1191]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 08:34:48 volumiohall sudo[1191]: pam_unix(sudo:session): session closed for user root Apr 20 08:34:50 volumiohall volumio[896]: info: Discovery: this is already registered, be4221ea-6182-4cf9-afa1-3c11a9ddc094 Apr 20 08:34:50 volumiohall volumio[896]: info: mDNS: Found device TEST Apr 20 08:34:50 volumiohall volumio[896]: info: CoreCommandRouter::volumioGetState Apr 20 08:34:50 volumiohall volumio[896]: info: CorePlayQueue::getTrack 0 Apr 20 08:34:55 volumiohall go-librespot[1140]: time="2024-04-20T08:34:55Z" level=debug msg="new websocket client" Apr 20 08:34:55 volumiohall volumio[896]: info: Connection to go-librespot Websocket established Apr 20 08:34:55 volumiohall volumio[896]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Apr 20 08:34:57 volumiohall sudo[1197]: volumio : unable to resolve host volumiohall Apr 20 08:34:57 volumiohall sudo[1197]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 08:34:57 volumiohall sudo[1197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.161.59.240/\\DESKTOP-0T8GMDB\Volumnio /mnt/NAS/PC_Hall Apr 20 08:34:57 volumiohall sudo[1197]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 08:34:57 volumiohall sudo[1197]: pam_unix(sudo:session): session closed for user root Apr 20 08:34:57 volumiohall kernel: CIFS: Attempting to mount //10.161.59.240/\\DESKTOP-0T8GMDB\Volumnio Apr 20 08:34:57 volumiohall kernel: CIFS: Status code returned 0xc0000022 STATUS_ACCESS_DENIED Apr 20 08:34:57 volumiohall kernel: CIFS: VFS: \\10.161.59.240 Send error in SessSetup = -13 Apr 20 08:34:57 volumiohall kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Apr 20 08:34:57 volumiohall volumio[896]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Apr 20 08:34:57 volumiohall sudo[1199]: volumio : unable to resolve host volumiohall Apr 20 08:34:57 volumiohall sudo[1199]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 08:34:57 volumiohall sudo[1199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 08:34:57 volumiohall sudo[1199]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 08:34:57 volumiohall sudo[1201]: volumio : unable to resolve host volumiohall Apr 20 08:34:57 volumiohall sudo[1199]: pam_unix(sudo:session): session closed for user root Apr 20 08:34:57 volumiohall sudo[1201]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 08:34:57 volumiohall sudo[1201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 08:34:57 volumiohall sudo[1201]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 08:34:57 volumiohall sudo[1201]: pam_unix(sudo:session): session closed for user root Apr 20 08:34:57 volumiohall volumio[896]: verbose: New Socket.io Connection to 10.161.59.248 from 10.161.59.240 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:125.0) Gecko/20100101 Firefox/125.0 Total Clients: 6 Apr 20 08:34:57 volumiohall volumio[896]: info: CoreCommandRouter::volumioGetState Apr 20 08:34:57 volumiohall volumio[896]: info: CorePlayQueue::getTrack 0 Apr 20 08:34:58 volumiohall volumio[896]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 20 08:34:58 volumiohall volumio[896]: info: CoreCommandRouter::volumioGetVisibleSources Apr 20 08:34:58 volumiohall volumio[896]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 08:34:58 volumiohall volumio[896]: info: Listing playlists Apr 20 08:34:58 volumiohall volumio[896]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 20 08:34:58 volumiohall volumio[896]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 20 08:34:58 volumiohall volumio[896]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 20 08:34:58 volumiohall volumio[896]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 08:34:58 volumiohall volumio[896]: info: Getting Spotify volume Apr 20 08:34:58 volumiohall volumio[896]: info: Spotify volume: 100 Apr 20 08:35:00 volumiohall volumio[896]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 20 08:35:00 volumiohall volumio[896]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 20 08:35:05 volumiohall volumio[896]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Apr 20 08:35:05 volumiohall volumio[896]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 08:35:05 volumiohall volumio[896]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 20 08:35:05 volumiohall volumio[896]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 20 08:35:06 volumiohall volumio[896]: info: Received Get System Version Apr 20 08:35:06 volumiohall volumio[896]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 08:35:10 volumiohall volumio[896]: SPOTIFY: User informations: {"display_name":"Alexis Begot","external_urls":{"spotify":"https://open.spotify.com/user/315ataumopucfkq3gm2h7qfphqna"},"href":"https://api.spotify.com/v1/users/315ataumopucfkq3gm2h7qfphqna","id":"315ataumopucfkq3gm2h7qfphqna","images":[],"type":"user","uri":"spotify:user:315ataumopucfkq3gm2h7qfphqna","followers":{"href":null,"total":0},"country":"FR","product":"premium","explicit_content":{"filter_enabled":false,"filter_locked":false},"email":"megaramaadjoint.annecy@gmail.com"} Apr 20 08:35:10 volumiohall volumio[896]: info: Spotify Successfully logged in Apr 20 08:35:10 volumiohall volumio[896]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 08:35:10 volumiohall volumio[896]: info: [1713602110698] CoreMusicLibrary::Adding element Spotify Apr 20 08:35:10 volumiohall volumio[896]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 08:35:10 volumiohall volumio[896]: Cannot find translation for sourceMedia Servers Apr 20 08:35:10 volumiohall volumio[896]: Cannot find translation for sourceSpotify Apr 20 08:35:13 volumiohall go-librespot[1140]: time="2024-04-20T08:35:13Z" level=debug msg="handling transfer player command from 1ab696bd4ae7b36095fb4f77e73f24f729959793" Apr 20 08:35:13 volumiohall go-librespot[1140]: time="2024-04-20T08:35:13Z" level=trace msg="fetched new page 0 with 109 items (list: 109)" Apr 20 08:35:13 volumiohall go-librespot[1140]: time="2024-04-20T08:35:13Z" level=debug msg="loading track spotify:track:1FI5hblwy9ZwyPg4Jr5hni (paused: false, position: 169613ms)" Apr 20 08:35:13 volumiohall go-librespot[1140]: time="2024-04-20T08:35:13Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1598" Apr 20 08:35:13 volumiohall go-librespot[1140]: time="2024-04-20T08:35:13Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 20 08:35:13 volumiohall go-librespot[1140]: time="2024-04-20T08:35:13Z" level=trace msg="emitting websocket event: will_play" Apr 20 08:35:13 volumiohall volumio[896]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1FI5hblwy9ZwyPg4Jr5hni","play_origin":"playlist"}} Apr 20 08:35:13 volumiohall go-librespot[1140]: time="2024-04-20T08:35:13Z" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:1FI5hblwy9ZwyPg4Jr5hni" Apr 20 08:35:13 volumiohall go-librespot[1140]: time="2024-04-20T08:35:13Z" level=debug msg="requested aes key for file 47d17abd750a82cefd205caaebfcbccb6cf02f4f, gid: 1FI5hblwy9ZwyPg4Jr5hni" Apr 20 08:35:15 volumiohall volumio[896]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Apr 20 08:35:18 volumiohall go-librespot[1140]: time="2024-04-20T08:35:18Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1602" Apr 20 08:35:19 volumiohall go-librespot[1140]: time="2024-04-20T08:35:19Z" level=debug msg="fetched first chunk of 15, total size is 7808080 bytes" Apr 20 08:35:19 volumiohall go-librespot[1140]: time="2024-04-20T08:35:19Z" level=debug msg="fetched chunk 1/14, size: 524288" Apr 20 08:35:19 volumiohall go-librespot[1140]: time="2024-04-20T08:35:19Z" level=debug msg="fetched chunk 3/14, size: 524288" Apr 20 08:35:19 volumiohall go-librespot[1140]: time="2024-04-20T08:35:19Z" level=debug msg="fetched chunk 2/14, size: 524288" Apr 20 08:35:19 volumiohall go-librespot[1140]: time="2024-04-20T08:35:19Z" level=debug msg="fetched chunk 13/14, size: 524288" Apr 20 08:35:19 volumiohall go-librespot[1140]: time="2024-04-20T08:35:19Z" level=debug msg="fetched chunk 12/14, size: 524288" Apr 20 08:35:20 volumiohall go-librespot[1140]: time="2024-04-20T08:35:20Z" level=debug msg="fetched chunk 14/14, size: 468048" Apr 20 08:35:20 volumiohall go-librespot[1140]: time="2024-04-20T08:35:20Z" level=debug msg="fetched chunk 11/14, size: 524288" Apr 20 08:35:20 volumiohall go-librespot[1140]: time="2024-04-20T08:35:20Z" level=trace msg="seek to 169613ms (diff: 346ms, samples: 7479933, bytes: 6231753)" Apr 20 08:35:20 volumiohall go-librespot[1140]: time="2024-04-20T08:35:20Z" level=info msg="loaded track \"Back To The Future: Back To The Future - From \"Back To The Future\"\" (uri: spotify:track:1FI5hblwy9ZwyPg4Jr5hni, paused: false, position: 169613ms, duration: 210960ms)" Apr 20 08:35:20 volumiohall go-librespot[1140]: time="2024-04-20T08:35:20Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 20 08:35:20 volumiohall go-librespot[1140]: time="2024-04-20T08:35:20Z" level=trace msg="emitting websocket event: metadata" Apr 20 08:35:20 volumiohall go-librespot[1140]: time="2024-04-20T08:35:20Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1598" Apr 20 08:35:20 volumiohall go-librespot[1140]: time="2024-04-20T08:35:20Z" level=trace msg="emitting websocket event: active" Apr 20 08:35:20 volumiohall go-librespot[1140]: time="2024-04-20T08:35:20Z" level=debug msg="sending successful reply for delaer request" Apr 20 08:35:20 volumiohall volumio[896]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1FI5hblwy9ZwyPg4Jr5hni","name":"Back To The Future: Back To The Future - From \"Back To The Future\"","artist_names":["Alan Silvestri"],"album_name":"The Back To The Future Trilogy","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02b74a35840be06420a6c7caf0","position":169613,"duration":210960,"release_date":"year:1999 month:9 day:20","track_number":1,"disc_number":1}} Apr 20 08:35:20 volumiohall volumio[896]: SPOTIFY: received: {"type":"active","data":null} Apr 20 08:35:20 volumiohall volumio[896]: info: Aligning Spotify Volume to Volumio Volume Apr 20 08:35:20 volumiohall volumio[896]: info: CoreCommandRouter::volumioGetState Apr 20 08:35:20 volumiohall volumio[896]: info: CorePlayQueue::getTrack 0 Apr 20 08:35:20 volumiohall volumio[896]: info: Setting Spotify Volume from Volumio: 90 Apr 20 08:35:20 volumiohall go-librespot[1140]: time="2024-04-20T08:35:20Z" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Apr 20 08:35:20 volumiohall go-librespot[1140]: time="2024-04-20T08:35:20Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 20 08:35:20 volumiohall go-librespot[1140]: time="2024-04-20T08:35:20Z" level=trace msg="emitting websocket event: playing" Apr 20 08:35:20 volumiohall volumio[896]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:1FI5hblwy9ZwyPg4Jr5hni","play_origin":"playlist"}} Apr 20 08:35:20 volumiohall volumio[896]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 08:35:20 volumiohall volumio[896]: TypeError: Cannot read property 'service' of undefined Apr 20 08:35:20 volumiohall volumio[896]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:336:50) Apr 20 08:35:20 volumiohall volumio[896]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:262:18) Apr 20 08:35:20 volumiohall volumio[896]: at WebSocket.message (/data/plugins/music_service/spop/index.js:189:14) Apr 20 08:35:20 volumiohall volumio[896]: at WebSocket.emit (events.js:315:20) Apr 20 08:35:20 volumiohall volumio[896]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1203:20) Apr 20 08:35:20 volumiohall volumio[896]: at Receiver.emit (events.js:315:20) Apr 20 08:35:20 volumiohall volumio[896]: at /data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:608:16 Apr 20 08:35:20 volumiohall volumio[896]: at internal/process/task_queues.js:149:7 Apr 20 08:35:20 volumiohall volumio[896]: at AsyncResource.runInAsyncScope (async_hooks.js:197:9) Apr 20 08:35:20 volumiohall volumio[896]: at AsyncResource.runMicrotask (internal/process/task_queues.js:146:8) Apr 20 08:35:20 volumiohall volumio[896]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 20 08:35:20 volumiohall volumio[896]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 08:35:23 volumiohall go-librespot[1140]: time="2024-04-20T08:35:23Z" level=debug msg="update volume to 46509/65535" Apr 20 08:35:23 volumiohall go-librespot[1140]: time="2024-04-20T08:35:23Z" level=debug msg="put connect state because VOLUME_CHANGED" Apr 20 08:35:23 volumiohall go-librespot[1140]: time="2024-04-20T08:35:23Z" level=trace msg="emitting websocket event: volume" Apr 20 08:35:31 volumiohall sudo[1229]: volumio : unable to resolve host volumiohall Apr 20 08:35:31 volumiohall sudo[1229]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 08:35:31 volumiohall sudo[1229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-20 08:34 Apr 20 08:35:31 volumiohall sudo[1229]: 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="e5f8795c07ba36ff2b02d7ba0a21c7a0f29b897e" VOLUMIO_FE_VERSION="473d8facbed5f9754db84a81b093baace7aa4b64" VOLUMIO_FE3_VERSION="934cddf6d46478e7a53a8d3664cd346f4de7cb34" VOLUMIO_BE_VERSION="359b2717b79cfc72b7d1c918d0fc2885a93bca70" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 24 Jan 2022 10:51:57 AM CET" VOLUMIO_VERSION="3.198" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="74e6a9ad035e912732893c8d6b060b17"