-- Logs begin at Sun 2025-06-08 16:48:45 EDT, end at Sun 2025-06-08 16:54:31 EDT. --
Jun 08 16:53:01 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 08 16:53:02 volumio volumio[1072]: info: CALLMETHOD: miscellanea my_music updateMusicLibrarySupersearchSources [object Object]
Jun 08 16:53:02 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_music , updateMusicLibrarySupersearchSources
Jun 08 16:53:02 volumio systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart.
Jun 08 16:53:02 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 4.
Jun 08 16:53:02 volumio systemd[1]: Stopped Volumio Qobuz Connect Service.
Jun 08 16:53:02 volumio systemd[1]: Started Volumio Qobuz Connect Service.
Jun 08 16:53:02 volumio volumio[1072]: info: Executing endpoint qc_getconfig
Jun 08 16:53:02 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Jun 08 16:53:02 volumio qobuz-connect[3061]: 20250608 16:53:02.842 [3061.3061] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Jun 08 16:53:02 volumio qobuz-connect[3061]: 20250608 16:53:02.844 [3061.3061] INFO VolumeManager: [0x1444b88]: Setting new playback volume: 75
Jun 08 16:53:02 volumio qobuz-connect[3061]: 20250608 16:53:02.844 [3061.3061] INFO VolumeManager: [0x1444b88]: Setting new mute state: 0
Jun 08 16:53:02 volumio qobuz-connect[3061]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed.
Jun 08 16:53:02 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT
Jun 08 16:53:02 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'.
Jun 08 16:53:02 volumio volumio[1072]: info: CoreCommandRouter::volumioGetBrowseSources
Jun 08 16:53:02 volumio volumio[1072]: info: CoreCommandRouter::volumioGetBrowseSources
Jun 08 16:53:02 volumio volumio[1072]: info: CoreCommandRouter::updateGlobalSearchableBrowseSources
Jun 08 16:53:03 volumio go-librespot[1634]: time="2025-06-08T16:53:03-04:00" level=trace msg="received accesspoint ping"
Jun 08 16:53:03 volumio go-librespot[1634]: time="2025-06-08T16:53:03-04:00" level=trace msg="received accesspoint pong ack"
Jun 08 16:53:03 volumio go-librespot[1634]: time="2025-06-08T16:53:03-04:00" level=trace msg="sent dealer ping"
Jun 08 16:53:03 volumio go-librespot[1634]: time="2025-06-08T16:53:03-04:00" level=trace msg="received dealer pong"
Jun 08 16:53:05 volumio volumio[1072]: error: error
Jun 08 16:53:05 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 08 16:53:07 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares
Jun 08 16:53:07 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService
Jun 08 16:53:08 volumio systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart.
Jun 08 16:53:08 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 5.
Jun 08 16:53:08 volumio systemd[1]: Stopped Volumio Qobuz Connect Service.
Jun 08 16:53:08 volumio systemd[1]: Started Volumio Qobuz Connect Service.
Jun 08 16:53:08 volumio volumio[1072]: info: Executing endpoint qc_getconfig
Jun 08 16:53:08 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Jun 08 16:53:08 volumio qobuz-connect[3080]: 20250608 16:53:08.090 [3080.3080] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Jun 08 16:53:08 volumio qobuz-connect[3080]: 20250608 16:53:08.092 [3080.3080] INFO VolumeManager: [0xce6b88]: Setting new playback volume: 75
Jun 08 16:53:08 volumio qobuz-connect[3080]: 20250608 16:53:08.092 [3080.3080] INFO VolumeManager: [0xce6b88]: Setting new mute state: 0
Jun 08 16:53:08 volumio qobuz-connect[3080]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed.
Jun 08 16:53:08 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT
Jun 08 16:53:08 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'.
Jun 08 16:53:09 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 08 16:53:13 volumio systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart.
Jun 08 16:53:13 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 6.
Jun 08 16:53:13 volumio systemd[1]: Stopped Volumio Qobuz Connect Service.
Jun 08 16:53:13 volumio systemd[1]: Started Volumio Qobuz Connect Service.
Jun 08 16:53:13 volumio volumio[1072]: info: Executing endpoint qc_getconfig
Jun 08 16:53:13 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Jun 08 16:53:13 volumio qobuz-connect[3085]: 20250608 16:53:13.343 [3085.3085] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Jun 08 16:53:13 volumio qobuz-connect[3085]: 20250608 16:53:13.345 [3085.3085] INFO VolumeManager: [0x2671b88]: Setting new playback volume: 75
Jun 08 16:53:13 volumio qobuz-connect[3085]: 20250608 16:53:13.345 [3085.3085] INFO VolumeManager: [0x2671b88]: Setting new mute state: 0
Jun 08 16:53:13 volumio qobuz-connect[3085]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed.
Jun 08 16:53:13 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT
Jun 08 16:53:13 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'.
Jun 08 16:53:13 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 08 16:53:18 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 08 16:53:18 volumio systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart.
Jun 08 16:53:18 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 7.
Jun 08 16:53:18 volumio systemd[1]: Stopped Volumio Qobuz Connect Service.
Jun 08 16:53:18 volumio systemd[1]: Started Volumio Qobuz Connect Service.
Jun 08 16:53:18 volumio volumio[1072]: info: Executing endpoint qc_getconfig
Jun 08 16:53:18 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Jun 08 16:53:18 volumio qobuz-connect[3101]: 20250608 16:53:18.595 [3101.3101] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Jun 08 16:53:18 volumio qobuz-connect[3101]: 20250608 16:53:18.597 [3101.3101] INFO VolumeManager: [0xcf9b88]: Setting new playback volume: 75
Jun 08 16:53:18 volumio qobuz-connect[3101]: 20250608 16:53:18.597 [3101.3101] INFO VolumeManager: [0xcf9b88]: Setting new mute state: 0
Jun 08 16:53:18 volumio qobuz-connect[3101]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed.
Jun 08 16:53:18 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT
Jun 08 16:53:18 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'.
Jun 08 16:53:20 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare
Jun 08 16:53:20 volumio volumio[1072]: info: Adding a new share
Jun 08 16:53:20 volumio volumio[1072]: info: No correspondence found in configuration for share nt-desktop on IP nt-desktop
Jun 08 16:53:20 volumio volumio[1072]: info: Executing SMB command: smbclient --debuglevel=4 -L nt-desktop -N
Jun 08 16:53:20 volumio volumio[1072]: info: Error browsing device nt-desktop for shares: Command failed: smbclient --debuglevel=4 -L nt-desktop -N
Jun 08 16:53:20 volumio volumio[1072]: lp_load_ex: refreshing parameters
Jun 08 16:53:20 volumio volumio[1072]: Initialising global parameters
Jun 08 16:53:20 volumio volumio[1072]: Processing section "[global]"
Jun 08 16:53:20 volumio volumio[1072]: doing parameter netbios name = Volumio
Jun 08 16:53:20 volumio volumio[1072]: doing parameter server string = Volumio Audiophile Music Player
Jun 08 16:53:20 volumio volumio[1072]: doing parameter workgroup = WORKGROUP
Jun 08 16:53:20 volumio volumio[1072]: doing parameter security = user
Jun 08 16:53:20 volumio volumio[1072]: doing parameter map to guest = Bad User
Jun 08 16:53:20 volumio volumio[1072]: doing parameter encrypt passwords = yes
Jun 08 16:53:20 volumio volumio[1072]: doing parameter wins support = yes
Jun 08 16:53:20 volumio volumio[1072]: doing parameter local master = no
Jun 08 16:53:20 volumio volumio[1072]: doing parameter preferred master = no
Jun 08 16:53:20 volumio volumio[1072]: doing parameter os level = 30
Jun 08 16:53:20 volumio volumio[1072]: doing parameter fruit:model = AirPort
Jun 08 16:53:20 volumio volumio[1072]: pm_process() returned Yes
Jun 08 16:53:20 volumio volumio[1072]: directory_create_or_exist_strict: invalid ownership on directory /var/run/samba/msg.lock
Jun 08 16:53:20 volumio volumio[1072]: cmdline_messaging_context: Unable to initialize messaging context.
Jun 08 16:53:20 volumio volumio[1072]: Unable to initialize messaging context
Jun 08 16:53:20 volumio volumio[1072]: lp_load_ex: refreshing parameters
Jun 08 16:53:20 volumio volumio[1072]: Initialising global parameters
Jun 08 16:53:20 volumio volumio[1072]: Processing section "[global]"
Jun 08 16:53:20 volumio volumio[1072]: doing parameter netbios name = Volumio
Jun 08 16:53:20 volumio volumio[1072]: doing parameter server string = Volumio Audiophile Music Player
Jun 08 16:53:20 volumio volumio[1072]: doing parameter workgroup = WORKGROUP
Jun 08 16:53:20 volumio volumio[1072]: doing parameter security = user
Jun 08 16:53:20 volumio volumio[1072]: doing parameter map to guest = Bad User
Jun 08 16:53:20 volumio volumio[1072]: doing parameter encrypt passwords = yes
Jun 08 16:53:20 volumio volumio[1072]: doing parameter wins support = yes
Jun 08 16:53:20 volumio volumio[1072]: doing parameter local master = no
Jun 08 16:53:20 volumio volumio[1072]: doing parameter preferred master = no
Jun 08 16:53:20 volumio volumio[1072]: doing parameter os level = 30
Jun 08 16:53:20 volumio volumio[1072]: doing parameter fruit:model = AirPort
Jun 08 16:53:20 volumio volumio[1072]: pm_process() returned Yes
Jun 08 16:53:20 volumio volumio[1072]: added interface eth0 ip=192.168.27.115 bcast=192.168.27.255 netmask=255.255.255.0
Jun 08 16:53:20 volumio volumio[1072]: added interface wlan0 ip=192.168.211.1 bcast=192.168.211.255 netmask=255.255.255.0
Jun 08 16:53:20 volumio volumio[1072]: Client started (version 4.9.5-Debian).
Jun 08 16:53:20 volumio volumio[1072]: tdb(/var/cache/samba/gencache.tdb): tdb_open_ex: could not open file /var/cache/samba/gencache.tdb: Permission denied
Jun 08 16:53:20 volumio volumio[1072]: resolve_lmhosts: Attempting lmhosts lookup for name nt-desktop<0x20>
Jun 08 16:53:20 volumio volumio[1072]: startlmhosts: Can't open lmhosts file /etc/samba/lmhosts. Error was No such file or directory
Jun 08 16:53:20 volumio volumio[1072]: wins_srv_is_dead: 127.0.0.1 is alive
Jun 08 16:53:20 volumio volumio[1072]: resolve_wins: using WINS server 127.0.0.1 and tag '*'
Jun 08 16:53:20 volumio volumio[1072]: nmb packet from 127.0.0.1(35072) header: id=19990 opcode=Query(0) response=Yes
Jun 08 16:53:20 volumio volumio[1072]: header: flags: bcast=No rec_avail=Yes rec_des=Yes trunc=No auth=Yes
Jun 08 16:53:20 volumio volumio[1072]: header: rcode=0 qdcount=0 ancount=1 nscount=0 arcount=0
Jun 08 16:53:20 volumio volumio[1072]: answers: nmb_name=NT-DESKTOP<20> rr_type=32 rr_class=1 ttl=7200
Jun 08 16:53:20 volumio volumio[1072]: answers 0 char .....X hex 0000C0A81B58
Jun 08 16:53:20 volumio volumio[1072]: Got a positive name query response from 127.0.0.1 ( 192.168.27.88 )
Jun 08 16:53:20 volumio volumio[1072]: Connecting to 192.168.27.88 at port 445
Jun 08 16:53:20 volumio volumio[1072]: session request ok
Jun 08 16:53:20 volumio volumio[1072]: negotiated dialect[SMB3_11] against server[nt-desktop]
Jun 08 16:53:20 volumio volumio[1072]: got OID=1.3.6.1.4.1.311.2.2.30
Jun 08 16:53:20 volumio volumio[1072]: got OID=1.3.6.1.4.1.311.2.2.10
Jun 08 16:53:20 volumio volumio[1072]: GENSEC backend 'gssapi_spnego' registered
Jun 08 16:53:20 volumio volumio[1072]: GENSEC backend 'gssapi_krb5' registered
Jun 08 16:53:20 volumio volumio[1072]: GENSEC backend 'gssapi_krb5_sasl' registered
Jun 08 16:53:20 volumio volumio[1072]: GENSEC backend 'spnego' registered
Jun 08 16:53:20 volumio volumio[1072]: GENSEC backend 'schannel' registered
Jun 08 16:53:20 volumio volumio[1072]: GENSEC backend 'naclrpc_as_system' registered
Jun 08 16:53:20 volumio volumio[1072]: GENSEC backend 'sasl-EXTERNAL' registered
Jun 08 16:53:20 volumio volumio[1072]: GENSEC backend 'ntlmssp' registered
Jun 08 16:53:20 volumio volumio[1072]: GENSEC backend 'ntlmssp_resume_ccache' registered
Jun 08 16:53:20 volumio volumio[1072]: GENSEC backend 'http_basic' registered
Jun 08 16:53:20 volumio volumio[1072]: GENSEC backend 'http_ntlm' registered
Jun 08 16:53:20 volumio volumio[1072]: GENSEC backend 'http_negotiate' registered
Jun 08 16:53:20 volumio volumio[1072]: GENSEC backend 'krb5' registered
Jun 08 16:53:20 volumio volumio[1072]: GENSEC backend 'fake_gssapi_krb5' registered
Jun 08 16:53:20 volumio volumio[1072]: Got challenge flags:
Jun 08 16:53:20 volumio volumio[1072]: Got NTLMSSP neg_flags=0x628a8215
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_UNICODE
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_REQUEST_TARGET
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_SIGN
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_NTLM
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_TARGET_TYPE_SERVER
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_TARGET_INFO
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_VERSION
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_128
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_KEY_EXCH
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP: Set final flags:
Jun 08 16:53:20 volumio volumio[1072]: Got NTLMSSP neg_flags=0x62008215
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_UNICODE
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_REQUEST_TARGET
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_SIGN
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_NTLM
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_VERSION
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_128
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_KEY_EXCH
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP Sign/Seal - Initialising with flags:
Jun 08 16:53:20 volumio volumio[1072]: Got NTLMSSP neg_flags=0x62008215
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_UNICODE
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_REQUEST_TARGET
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_SIGN
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_NTLM
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_VERSION
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_128
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_KEY_EXCH
Jun 08 16:53:20 volumio volumio[1072]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information.
Jun 08 16:53:20 volumio volumio[1072]: got OID=1.3.6.1.4.1.311.2.2.30
Jun 08 16:53:20 volumio volumio[1072]: got OID=1.3.6.1.4.1.311.2.2.10
Jun 08 16:53:20 volumio volumio[1072]: Got challenge flags:
Jun 08 16:53:20 volumio volumio[1072]: Got NTLMSSP neg_flags=0x628a8215
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_UNICODE
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_REQUEST_TARGET
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_SIGN
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_NTLM
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_TARGET_TYPE_SERVER
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_TARGET_INFO
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_VERSION
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_128
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_KEY_EXCH
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP: Set final flags:
Jun 08 16:53:20 volumio volumio[1072]: Got NTLMSSP neg_flags=0x62008a15
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_UNICODE
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_REQUEST_TARGET
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_SIGN
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_NTLM
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_ANONYMOUS
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_VERSION
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_128
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_KEY_EXCH
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP Sign/Seal - Initialising with flags:
Jun 08 16:53:20 volumio volumio[1072]: Got NTLMSSP neg_flags=0x62008a15
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_UNICODE
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_REQUEST_TARGET
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_SIGN
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_NTLM
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_ANONYMOUS
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_VERSION
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_128
Jun 08 16:53:20 volumio volumio[1072]: NTLMSSP_NEGOTIATE_KEY_EXCH
Jun 08 16:53:20 volumio volumio[1072]: SPNEGO login failed: {Access Denied} A process has requested access to an object but has not been granted those access rights.
Jun 08 16:53:20 volumio volumio[1072]: info: Detected device nt-desktop with version null
Jun 08 16:53:20 volumio sudo[3108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=mr.nicholastabor@gmail.com,password=gEm7IuyIVeoi,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //nt-desktop/Music /mnt/NAS/nt-desktop
Jun 08 16:53:20 volumio sudo[3108]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 08 16:53:21 volumio kernel: Key type cifs.spnego registered
Jun 08 16:53:21 volumio kernel: Key type cifs.idmap registered
Jun 08 16:53:21 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount.
Jun 08 16:53:21 volumio kernel: CIFS: Attempting to mount //nt-desktop/Music
Jun 08 16:53:21 volumio sudo[3108]: pam_unix(sudo:session): session closed for user root
Jun 08 16:53:21 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE
Jun 08 16:53:21 volumio kernel: CIFS: VFS: \\nt-desktop Send error in SessSetup = -13
Jun 08 16:53:21 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13
Jun 08 16:53:21 volumio volumio[1072]: info: Permission denied for nt-desktop on IP nt-desktop
Jun 08 16:53:21 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 08 16:53:22 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Jun 08 16:53:23 volumio systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart.
Jun 08 16:53:23 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 8.
Jun 08 16:53:23 volumio systemd[1]: Stopped Volumio Qobuz Connect Service.
Jun 08 16:53:23 volumio systemd[1]: Started Volumio Qobuz Connect Service.
Jun 08 16:53:23 volumio volumio[1072]: info: Executing endpoint qc_getconfig
Jun 08 16:53:23 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Jun 08 16:53:23 volumio qobuz-connect[3137]: 20250608 16:53:23.843 [3137.3137] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Jun 08 16:53:23 volumio qobuz-connect[3137]: 20250608 16:53:23.845 [3137.3137] INFO VolumeManager: [0xaecb88]: Setting new playback volume: 75
Jun 08 16:53:23 volumio qobuz-connect[3137]: 20250608 16:53:23.845 [3137.3137] INFO VolumeManager: [0xaecb88]: Setting new mute state: 0
Jun 08 16:53:23 volumio qobuz-connect[3137]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed.
Jun 08 16:53:23 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT
Jun 08 16:53:23 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'.
Jun 08 16:53:25 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 08 16:53:29 volumio systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart.
Jun 08 16:53:29 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 9.
Jun 08 16:53:29 volumio systemd[1]: Stopped Volumio Qobuz Connect Service.
Jun 08 16:53:29 volumio systemd[1]: Started Volumio Qobuz Connect Service.
Jun 08 16:53:29 volumio volumio[1072]: info: Executing endpoint qc_getconfig
Jun 08 16:53:29 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Jun 08 16:53:29 volumio qobuz-connect[3153]: 20250608 16:53:29.094 [3153.3153] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Jun 08 16:53:29 volumio qobuz-connect[3153]: 20250608 16:53:29.097 [3153.3153] INFO VolumeManager: [0x13bbb88]: Setting new playback volume: 75
Jun 08 16:53:29 volumio qobuz-connect[3153]: 20250608 16:53:29.097 [3153.3153] INFO VolumeManager: [0x13bbb88]: Setting new mute state: 0
Jun 08 16:53:29 volumio qobuz-connect[3153]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed.
Jun 08 16:53:29 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT
Jun 08 16:53:29 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'.
Jun 08 16:53:30 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 08 16:53:33 volumio go-librespot[1634]: time="2025-06-08T16:53:33-04:00" level=trace msg="sent dealer ping"
Jun 08 16:53:33 volumio go-librespot[1634]: time="2025-06-08T16:53:33-04:00" level=trace msg="received dealer pong"
Jun 08 16:53:34 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 08 16:53:34 volumio systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart.
Jun 08 16:53:34 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 10.
Jun 08 16:53:34 volumio systemd[1]: Stopped Volumio Qobuz Connect Service.
Jun 08 16:53:34 volumio systemd[1]: Started Volumio Qobuz Connect Service.
Jun 08 16:53:34 volumio volumio[1072]: info: Executing endpoint qc_getconfig
Jun 08 16:53:34 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Jun 08 16:53:34 volumio qobuz-connect[3172]: 20250608 16:53:34.338 [3172.3172] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Jun 08 16:53:34 volumio qobuz-connect[3172]: 20250608 16:53:34.341 [3172.3172] INFO VolumeManager: [0x1b2ab88]: Setting new playback volume: 75
Jun 08 16:53:34 volumio qobuz-connect[3172]: 20250608 16:53:34.341 [3172.3172] INFO VolumeManager: [0x1b2ab88]: Setting new mute state: 0
Jun 08 16:53:34 volumio qobuz-connect[3172]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed.
Jun 08 16:53:34 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT
Jun 08 16:53:34 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'.
Jun 08 16:53:38 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 08 16:53:38 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare
Jun 08 16:53:38 volumio volumio[1072]: info: Share nt-desktop successfully unmounted
Jun 08 16:53:38 volumio sudo[3175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=share,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //nt-desktop/Music /mnt/NAS/nt-desktop
Jun 08 16:53:38 volumio sudo[3175]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 08 16:53:38 volumio kernel: CIFS: Attempting to mount //nt-desktop/Music
Jun 08 16:53:38 volumio sudo[3175]: pam_unix(sudo:session): session closed for user root
Jun 08 16:53:38 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13
Jun 08 16:53:38 volumio volumio[1072]: info: An error occurred mounting the new share. Rolling back configuration
Jun 08 16:53:39 volumio systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart.
Jun 08 16:53:39 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 11.
Jun 08 16:53:39 volumio systemd[1]: Stopped Volumio Qobuz Connect Service.
Jun 08 16:53:39 volumio systemd[1]: Started Volumio Qobuz Connect Service.
Jun 08 16:53:39 volumio volumio[1072]: info: Executing endpoint qc_getconfig
Jun 08 16:53:39 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Jun 08 16:53:39 volumio qobuz-connect[3180]: 20250608 16:53:39.606 [3180.3180] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Jun 08 16:53:39 volumio qobuz-connect[3180]: 20250608 16:53:39.608 [3180.3180] INFO VolumeManager: [0x1ab1b88]: Setting new playback volume: 75
Jun 08 16:53:39 volumio qobuz-connect[3180]: 20250608 16:53:39.608 [3180.3180] INFO VolumeManager: [0x1ab1b88]: Setting new mute state: 0
Jun 08 16:53:39 volumio qobuz-connect[3180]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed.
Jun 08 16:53:39 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT
Jun 08 16:53:39 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'.
Jun 08 16:53:42 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 08 16:53:44 volumio systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart.
Jun 08 16:53:44 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 12.
Jun 08 16:53:44 volumio systemd[1]: Stopped Volumio Qobuz Connect Service.
Jun 08 16:53:44 volumio systemd[1]: Started Volumio Qobuz Connect Service.
Jun 08 16:53:44 volumio volumio[1072]: info: Executing endpoint qc_getconfig
Jun 08 16:53:44 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Jun 08 16:53:44 volumio qobuz-connect[3196]: 20250608 16:53:44.842 [3196.3196] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Jun 08 16:53:44 volumio qobuz-connect[3196]: 20250608 16:53:44.844 [3196.3196] INFO VolumeManager: [0x2538b88]: Setting new playback volume: 75
Jun 08 16:53:44 volumio qobuz-connect[3196]: 20250608 16:53:44.844 [3196.3196] INFO VolumeManager: [0x2538b88]: Setting new mute state: 0
Jun 08 16:53:44 volumio qobuz-connect[3196]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed.
Jun 08 16:53:44 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT
Jun 08 16:53:44 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'.
Jun 08 16:53:46 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 08 16:53:50 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 08 16:53:50 volumio systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart.
Jun 08 16:53:50 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 13.
Jun 08 16:53:50 volumio systemd[1]: Stopped Volumio Qobuz Connect Service.
Jun 08 16:53:50 volumio systemd[1]: Started Volumio Qobuz Connect Service.
Jun 08 16:53:50 volumio volumio[1072]: info: Executing endpoint qc_getconfig
Jun 08 16:53:50 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Jun 08 16:53:50 volumio qobuz-connect[3240]: 20250608 16:53:50.094 [3240.3240] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Jun 08 16:53:50 volumio qobuz-connect[3240]: 20250608 16:53:50.096 [3240.3240] INFO VolumeManager: [0x2700b88]: Setting new playback volume: 75
Jun 08 16:53:50 volumio qobuz-connect[3240]: 20250608 16:53:50.096 [3240.3240] INFO VolumeManager: [0x2700b88]: Setting new mute state: 0
Jun 08 16:53:50 volumio qobuz-connect[3240]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed.
Jun 08 16:53:50 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT
Jun 08 16:53:50 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'.
Jun 08 16:53:54 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState
Jun 08 16:53:55 volumio volumio[1072]: error: error
Jun 08 16:53:55 volumio systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart.
Jun 08 16:53:55 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 14.
Jun 08 16:53:55 volumio systemd[1]: Stopped Volumio Qobuz Connect Service.
Jun 08 16:53:55 volumio systemd[1]: Started Volumio Qobuz Connect Service.
Jun 08 16:53:55 volumio volumio[1072]: info: Executing endpoint qc_getconfig
Jun 08 16:53:55 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Jun 08 16:53:55 volumio qobuz-connect[3259]: 20250608 16:53:55.346 [3259.3259] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Jun 08 16:53:55 volumio qobuz-connect[3259]: 20250608 16:53:55.348 [3259.3259] INFO VolumeManager: [0x22a4b88]: Setting new playback volume: 75
Jun 08 16:53:55 volumio qobuz-connect[3259]: 20250608 16:53:55.348 [3259.3259] INFO VolumeManager: [0x22a4b88]: Setting new mute state: 0
Jun 08 16:53:55 volumio qobuz-connect[3259]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed.
Jun 08 16:53:55 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT
Jun 08 16:53:55 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'.
Jun 08 16:53:56 volumio volumio[1072]: info: Executing endpoint metavolumio
Jun 08 16:53:56 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jun 08 16:53:56 volumio volumio[1072]: info: Executing endpoint metavolumio
Jun 08 16:53:56 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jun 08 16:53:58 volumio volumio[1072]: info: CoreCommandRouter::volumioGetQueue
Jun 08 16:53:58 volumio volumio[1072]: info: CoreStateMachine::getQueue
Jun 08 16:53:58 volumio volumio[1072]: info: CorePlayQueue::getQueue
Jun 08 16:54:00 volumio volumio[1072]: info: Preload queue cleared
Jun 08 16:54:00 volumio volumio[1072]: info: CoreCommandRouter::volumioReplaceandPlayItems
Jun 08 16:54:00 volumio volumio[1072]: info: CoreStateMachine::ClearQueue
Jun 08 16:54:00 volumio volumio[1072]: info: CoreStateMachine::stop
Jun 08 16:54:00 volumio volumio[1072]: info: CoreStateMachine::setConsumeUpdateService undefined
Jun 08 16:54:00 volumio volumio[1072]: info: CoreStateMachine::stPlaybackTimer
Jun 08 16:54:00 volumio volumio[1072]: info: CoreStateMachine::updateTrackBlock
Jun 08 16:54:00 volumio volumio[1072]: info: CorePlayQueue::getTrackBlock
Jun 08 16:54:00 volumio volumio[1072]: info: CoreStateMachine::pushState
Jun 08 16:54:00 volumio volumio[1072]: info: CorePlayQueue::getTrack 0
Jun 08 16:54:00 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 08 16:54:00 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState
Jun 08 16:54:00 volumio volumio[1072]: info: MRS: Pushing multiroomSync output update for this device
Jun 08 16:54:00 volumio volumio[1072]: info: MRS: Pushing multiroomSync output
Jun 08 16:54:00 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState
Jun 08 16:54:00 volumio volumio[1072]: info: CorePlayQueue::getTrack 0
Jun 08 16:54:00 volumio volumio[1072]: info: MRS: Pushing multiroomSync output update for this device
Jun 08 16:54:00 volumio volumio[1072]: info: MRS: Pushing multiroomSync output
Jun 08 16:54:00 volumio volumio[1072]: info: CoreStateMachine::serviceStop
Jun 08 16:54:00 volumio volumio[1072]: info: CorePlayQueue::getTrack 0
Jun 08 16:54:00 volumio volumio[1072]: info: CoreCommandRouter::serviceStop
Jun 08 16:54:00 volumio volumio[1072]: info: [1749416040378] ControllerWebradio::stop
Jun 08 16:54:00 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand stop
Jun 08 16:54:00 volumio volumio[1072]: info: CorePlayQueue::clearPlayQueue
Jun 08 16:54:00 volumio volumio[1072]: info: CorePlayQueue::saveQueue
Jun 08 16:54:00 volumio volumio[1072]: info: CoreCommandRouter::volumioPushQueue
Jun 08 16:54:00 volumio volumio[1072]: info: CoreStateMachine::addQueueItems
Jun 08 16:54:00 volumio volumio[1072]: info: CorePlayQueue::addQueueItems
Jun 08 16:54:00 volumio volumio[1072]: info: Preload queue cleared
Jun 08 16:54:00 volumio volumio[1072]: info: Adding Item to queue: https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3
Jun 08 16:54:00 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
Jun 08 16:54:00 volumio volumio[1072]: info: CoreCommandRouter::volumioPushQueue
Jun 08 16:54:00 volumio volumio[1072]: info: CorePlayQueue::saveQueue
Jun 08 16:54:00 volumio volumio[1072]: info: CoreStateMachine::updateTrackBlock
Jun 08 16:54:00 volumio volumio[1072]: info: CorePlayQueue::getTrackBlock
Jun 08 16:54:00 volumio volumio[1072]: info: CoreCommandRouter::volumioPlay
Jun 08 16:54:00 volumio volumio[1072]: info: CoreStateMachine::play index 0
Jun 08 16:54:00 volumio volumio[1072]: info: CoreStateMachine::setConsumeUpdateService undefined
Jun 08 16:54:00 volumio volumio[1072]: info: CoreStateMachine::stop
Jun 08 16:54:00 volumio volumio[1072]: info: CoreStateMachine::setConsumeUpdateService undefined
Jun 08 16:54:00 volumio volumio[1072]: info: CoreStateMachine::play index undefined
Jun 08 16:54:00 volumio volumio[1072]: info: CoreStateMachine::setConsumeUpdateService undefined
Jun 08 16:54:00 volumio volumio[1072]: info: CorePlayQueue::getTrack 0
Jun 08 16:54:00 volumio volumio[1072]: info: CoreStateMachine::startPlaybackTimer
Jun 08 16:54:00 volumio volumio[1072]: info: CorePlayQueue::getTrack 0
Jun 08 16:54:00 volumio volumio[1072]: info: CoreCommandRouter::volumioGetVisibleSources
Jun 08 16:54:00 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 08 16:54:00 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Jun 08 16:54:00 volumio volumio[1072]: info: [1749416040393] ControllerWebradio::clearAddPlayTrack
Jun 08 16:54:00 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand stop
Jun 08 16:54:00 volumio volumio[1072]: info: sendMpdCommand stop took 42 milliseconds
Jun 08 16:54:00 volumio volumio[1072]: info: sendMpdCommand stop took 26 milliseconds
Jun 08 16:54:00 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand clear
Jun 08 16:54:00 volumio volumio[1072]: info:
Jun 08 16:54:00 volumio volumio[1072]: ---------------------------- MPD announces system playlist update
Jun 08 16:54:00 volumio volumio[1072]: info: Ignoring MPD Status Update
Jun 08 16:54:00 volumio volumio[1072]: info: sendMpdCommand clear took 9 milliseconds
Jun 08 16:54:00 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand load "https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3"
Jun 08 16:54:00 volumio volumio[1072]: error: updateQueue error: null
Jun 08 16:54:00 volumio volumio[1072]: info: ------------------------------ 2ms
Jun 08 16:54:00 volumio volumio[1072]: info: touch_display: Setting screensaver timeout to 120 seconds.
Jun 08 16:54:00 volumio systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart.
Jun 08 16:54:00 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 15.
Jun 08 16:54:00 volumio systemd[1]: Stopped Volumio Qobuz Connect Service.
Jun 08 16:54:00 volumio systemd[1]: Started Volumio Qobuz Connect Service.
Jun 08 16:54:00 volumio volumio[1072]: info: Executing endpoint qc_getconfig
Jun 08 16:54:00 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Jun 08 16:54:00 volumio qobuz-connect[3267]: 20250608 16:54:00.595 [3267.3267] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Jun 08 16:54:00 volumio qobuz-connect[3267]: 20250608 16:54:00.597 [3267.3267] INFO VolumeManager: [0x14c5b88]: Setting new playback volume: 75
Jun 08 16:54:00 volumio qobuz-connect[3267]: 20250608 16:54:00.597 [3267.3267] INFO VolumeManager: [0x14c5b88]: Setting new mute state: 0
Jun 08 16:54:00 volumio qobuz-connect[3267]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed.
Jun 08 16:54:00 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT
Jun 08 16:54:00 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'.
Jun 08 16:54:02 volumio volumio[1072]: info: Executing endpoint metavolumio
Jun 08 16:54:02 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jun 08 16:54:03 volumio go-librespot[1634]: time="2025-06-08T16:54:03-04:00" level=trace msg="sent dealer ping"
Jun 08 16:54:03 volumio go-librespot[1634]: time="2025-06-08T16:54:03-04:00" level=trace msg="received dealer pong"
Jun 08 16:54:05 volumio systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart.
Jun 08 16:54:05 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 16.
Jun 08 16:54:05 volumio systemd[1]: Stopped Volumio Qobuz Connect Service.
Jun 08 16:54:05 volumio systemd[1]: Started Volumio Qobuz Connect Service.
Jun 08 16:54:05 volumio volumio[1072]: info: Executing endpoint qc_getconfig
Jun 08 16:54:05 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Jun 08 16:54:05 volumio qobuz-connect[3283]: 20250608 16:54:05.842 [3283.3283] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Jun 08 16:54:05 volumio qobuz-connect[3283]: 20250608 16:54:05.844 [3283.3283] INFO VolumeManager: [0x2031b88]: Setting new playback volume: 75
Jun 08 16:54:05 volumio qobuz-connect[3283]: 20250608 16:54:05.844 [3283.3283] INFO VolumeManager: [0x2031b88]: Setting new mute state: 0
Jun 08 16:54:05 volumio qobuz-connect[3283]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed.
Jun 08 16:54:05 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT
Jun 08 16:54:05 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'.
Jun 08 16:54:08 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Jun 08 16:54:08 volumio volumio[1072]: info: CURURI: music-library
Jun 08 16:54:11 volumio systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart.
Jun 08 16:54:11 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 17.
Jun 08 16:54:11 volumio systemd[1]: Stopped Volumio Qobuz Connect Service.
Jun 08 16:54:11 volumio systemd[1]: Started Volumio Qobuz Connect Service.
Jun 08 16:54:11 volumio volumio[1072]: info: Executing endpoint qc_getconfig
Jun 08 16:54:11 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Jun 08 16:54:11 volumio qobuz-connect[3288]: 20250608 16:54:11.094 [3288.3288] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Jun 08 16:54:11 volumio qobuz-connect[3288]: 20250608 16:54:11.097 [3288.3288] INFO VolumeManager: [0x1ad7b88]: Setting new playback volume: 75
Jun 08 16:54:11 volumio qobuz-connect[3288]: 20250608 16:54:11.097 [3288.3288] INFO VolumeManager: [0x1ad7b88]: Setting new mute state: 0
Jun 08 16:54:11 volumio qobuz-connect[3288]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed.
Jun 08 16:54:11 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT
Jun 08 16:54:11 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'.
Jun 08 16:54:11 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand add "https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3"
Jun 08 16:54:11 volumio volumio[1072]: error: Failed LSINFO: null
Jun 08 16:54:11 volumio volumio[1072]: info:
Jun 08 16:54:11 volumio volumio[1072]: ---------------------------- MPD announces system playlist update
Jun 08 16:54:11 volumio volumio[1072]: info: Ignoring MPD Status Update
Jun 08 16:54:11 volumio volumio[1072]: info: sendMpdCommand add "https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3" took 2 milliseconds
Jun 08 16:54:11 volumio volumio[1072]: info: CoreStateMachine::setConsumeUpdateService mpd
Jun 08 16:54:11 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand play
Jun 08 16:54:11 volumio volumio[1072]: info: ------------------------------ 2ms
Jun 08 16:54:11 volumio volumio[1072]: info: sendMpdCommand play took 4 milliseconds
Jun 08 16:54:11 volumio volumio[1072]: info: Preload queue cleared
Jun 08 16:54:16 volumio systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart.
Jun 08 16:54:16 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 18.
Jun 08 16:54:16 volumio systemd[1]: Stopped Volumio Qobuz Connect Service.
Jun 08 16:54:16 volumio systemd[1]: Started Volumio Qobuz Connect Service.
Jun 08 16:54:16 volumio volumio[1072]: info: Executing endpoint qc_getconfig
Jun 08 16:54:16 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Jun 08 16:54:16 volumio qobuz-connect[3307]: 20250608 16:54:16.343 [3307.3307] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Jun 08 16:54:16 volumio qobuz-connect[3307]: 20250608 16:54:16.345 [3307.3307] INFO VolumeManager: [0x2344b88]: Setting new playback volume: 75
Jun 08 16:54:16 volumio qobuz-connect[3307]: 20250608 16:54:16.345 [3307.3307] INFO VolumeManager: [0x2344b88]: Setting new mute state: 0
Jun 08 16:54:16 volumio qobuz-connect[3307]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed.
Jun 08 16:54:16 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT
Jun 08 16:54:16 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'.
Jun 08 16:54:21 volumio systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart.
Jun 08 16:54:21 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 19.
Jun 08 16:54:21 volumio systemd[1]: Stopped Volumio Qobuz Connect Service.
Jun 08 16:54:21 volumio systemd[1]: Started Volumio Qobuz Connect Service.
Jun 08 16:54:21 volumio volumio[1072]: info: Executing endpoint qc_getconfig
Jun 08 16:54:21 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Jun 08 16:54:21 volumio qobuz-connect[3310]: 20250608 16:54:21.594 [3310.3310] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Jun 08 16:54:21 volumio qobuz-connect[3310]: 20250608 16:54:21.596 [3310.3310] INFO VolumeManager: [0xe09b88]: Setting new playback volume: 75
Jun 08 16:54:21 volumio qobuz-connect[3310]: 20250608 16:54:21.596 [3310.3310] INFO VolumeManager: [0xe09b88]: Setting new mute state: 0
Jun 08 16:54:21 volumio qobuz-connect[3310]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed.
Jun 08 16:54:21 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT
Jun 08 16:54:21 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'.
Jun 08 16:54:22 volumio volumio[1072]: info:
Jun 08 16:54:22 volumio volumio[1072]: ---------------------------- MPD announces state update: player
Jun 08 16:54:22 volumio volumio[1072]: info: ControllerMpd::getState
Jun 08 16:54:22 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status
Jun 08 16:54:22 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand clearerror
Jun 08 16:54:22 volumio volumio[1072]: info: sendMpdCommand status took 6 milliseconds
Jun 08 16:54:22 volumio volumio[1072]: verbose: ControllerMpd::parseState
Jun 08 16:54:22 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 08 16:54:22 volumio volumio[1072]: info: sendMpdCommand clearerror took 1 milliseconds
Jun 08 16:54:22 volumio volumio[1072]: info: sendMpdCommand playlistinfo took 2 milliseconds
Jun 08 16:54:22 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo
Jun 08 16:54:22 volumio volumio[1072]: info: ControllerMpd::pushState
Jun 08 16:54:22 volumio volumio[1072]: info: CoreCommandRouter::servicePushState
Jun 08 16:54:22 volumio volumio[1072]: info: CoreStateMachine::pushState
Jun 08 16:54:22 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 08 16:54:22 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState
Jun 08 16:54:22 volumio volumio[1072]: info: MRS: Pushing multiroomSync output update for this device
Jun 08 16:54:22 volumio volumio[1072]: info: MRS: Pushing multiroomSync output
Jun 08 16:54:22 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState
Jun 08 16:54:22 volumio volumio[1072]: info: MRS: Pushing multiroomSync output update for this device
Jun 08 16:54:22 volumio volumio[1072]: info: MRS: Pushing multiroomSync output
Jun 08 16:54:22 volumio volumio[1072]: info: CorePlayQueue::getTrack 0
Jun 08 16:54:22 volumio volumio[1072]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3","artist":null,"album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""}
Jun 08 16:54:22 volumio volumio[1072]: verbose: CURRENT POSITION 0
Jun 08 16:54:22 volumio volumio[1072]: info: CoreStateMachine::syncState stateService stop
Jun 08 16:54:22 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus stop
Jun 08 16:54:22 volumio volumio[1072]: info: CoreStateMachine::pushState
Jun 08 16:54:22 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState
Jun 08 16:54:22 volumio volumio[1072]: info: MRS: Pushing multiroomSync output update for this device
Jun 08 16:54:22 volumio volumio[1072]: info: MRS: Pushing multiroomSync output
Jun 08 16:54:22 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState
Jun 08 16:54:22 volumio volumio[1072]: info: MRS: Pushing multiroomSync output update for this device
Jun 08 16:54:22 volumio volumio[1072]: info: MRS: Pushing multiroomSync output
Jun 08 16:54:22 volumio volumio[1072]: info: No code
Jun 08 16:54:22 volumio volumio[1072]: info: CoreStateMachine::pushState
Jun 08 16:54:22 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState
Jun 08 16:54:22 volumio volumio[1072]: info: MRS: Pushing multiroomSync output update for this device
Jun 08 16:54:22 volumio volumio[1072]: info: MRS: Pushing multiroomSync output
Jun 08 16:54:22 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState
Jun 08 16:54:22 volumio volumio[1072]: info: MRS: Pushing multiroomSync output update for this device
Jun 08 16:54:22 volumio volumio[1072]: info: MRS: Pushing multiroomSync output
Jun 08 16:54:22 volumio volumio[1072]: info: ------------------------------ 39ms
Jun 08 16:54:24 volumio volumio[1072]: info: Executing endpoint metavolumio
Jun 08 16:54:24 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jun 08 16:54:24 volumio volumio[1072]: info: Executing endpoint metavolumio
Jun 08 16:54:24 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jun 08 16:54:26 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jun 08 16:54:26 volumio volumio[1072]: info: Preload queue cleared
Jun 08 16:54:26 volumio systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart.
Jun 08 16:54:26 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 20.
Jun 08 16:54:26 volumio systemd[1]: Stopped Volumio Qobuz Connect Service.
Jun 08 16:54:26 volumio systemd[1]: Started Volumio Qobuz Connect Service.
Jun 08 16:54:26 volumio volumio[1072]: info: Executing endpoint qc_getconfig
Jun 08 16:54:26 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Jun 08 16:54:26 volumio qobuz-connect[3327]: 20250608 16:54:26.846 [3327.3327] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Jun 08 16:54:26 volumio qobuz-connect[3327]: 20250608 16:54:26.848 [3327.3327] INFO VolumeManager: [0x1fceb88]: Setting new playback volume: 75
Jun 08 16:54:26 volumio qobuz-connect[3327]: 20250608 16:54:26.848 [3327.3327] INFO VolumeManager: [0x1fceb88]: Setting new mute state: 0
Jun 08 16:54:26 volumio qobuz-connect[3327]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed.
Jun 08 16:54:26 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT
Jun 08 16:54:26 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'.
Jun 08 16:54:27 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jun 08 16:54:28 volumio volumio[1072]: info: Preload queue cleared
Jun 08 16:54:29 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jun 08 16:54:29 volumio volumio[1072]: info: Preload queue cleared
Jun 08 16:54:31 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jun 08 16:54:31 volumio volumio[1072]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jun 08 16:54:31 volumio volumio[1072]: TypeError: Cannot read property 'length' of undefined
Jun 08 16:54:31 volumio volumio[1072]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25
Jun 08 16:54:31 volumio volumio[1072]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13
Jun 08 16:54:31 volumio volumio[1072]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18)
Jun 08 16:54:31 volumio volumio[1072]: at Parser.emit (events.js:315:20)
Jun 08 16:54:31 volumio volumio[1072]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26)
Jun 08 16:54:31 volumio volumio[1072]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35)
Jun 08 16:54:31 volumio volumio[1072]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5)
Jun 08 16:54:31 volumio volumio[1072]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7)
Jun 08 16:54:31 volumio volumio[1072]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13)
Jun 08 16:54:31 volumio volumio[1072]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31)
Jun 08 16:54:31 volumio volumio[1072]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59)
Jun 08 16:54:31 volumio volumio[1072]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19)
Jun 08 16:54:31 volumio volumio[1072]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15)
Jun 08 16:54:31 volumio volumio[1072]: at IncomingMessage.emit (events.js:327:22)
Jun 08 16:54:31 volumio volumio[1072]: at endReadableNT (internal/streams/readable.js:1327:12)
Jun 08 16:54:31 volumio volumio[1072]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Jun 08 16:54:31 volumio volumio[1072]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jun 08 16:54:31 volumio sudo[3342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-08 16:53
Jun 08 16:54:31 volumio sudo[3342]: 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="570c5791513f5bac7da274aba6690c1a961705de"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST"
VOLUMIO_VERSION="3.812"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"