-- Logs begin at Sun 2024-05-05 13:17:44 UTC, end at Fri 2025-03-14 16:16:55 UTC. -- Mar 14 16:15:04 volumio systemd[1]: Time has been changed Mar 14 16:15:04 volumio volumio[926]: info: Loading plugin "mpd"... Mar 14 16:15:04 volumio nmbd[725]: [2025/03/14 16:15:04.374868, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 14 16:15:04 volumio nmbd[725]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.57 for name WORKGROUP<1d>. Mar 14 16:15:04 volumio nmbd[725]: This response was from IP 192.168.1.54, reporting an IP address of 192.168.1.54. Mar 14 16:15:04 volumio volumio[926]: info: Loading plugin "upnp_browser"... Mar 14 16:15:06 volumio volumio[926]: info: Loading plugin "networkfs"... Mar 14 16:15:06 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 14 16:15:06 volumio volumio[926]: info: Loading plugin "alarm-clock"... Mar 14 16:15:06 volumio sudo[950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=jerome,password=tsttst,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.55/ /mnt/NAS/musique-pc Mar 14 16:15:06 volumio sudo[950]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 14 16:15:06 volumio sudo[952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=jerome,password=tsttst,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.55/ /mnt/NAS/classique-pc Mar 14 16:15:06 volumio sudo[952]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 14 16:15:06 volumio kernel: FS-Cache: Netfs 'cifs' registered for caching Mar 14 16:15:06 volumio kernel: Key type cifs.spnego registered Mar 14 16:15:06 volumio kernel: Key type cifs.idmap registered Mar 14 16:15:06 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Mar 14 16:15:06 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Mar 14 16:15:07 volumio volumio[926]: info: Loading plugin "airplay_emulation"... Mar 14 16:15:07 volumio volumio[926]: info: [1741968907113] Starting Shairport Sync Mar 14 16:15:07 volumio volumio[926]: info: Loading plugin "last_100"... Mar 14 16:15:07 volumio volumio[926]: info: Loading plugin "webradio"... Mar 14 16:15:07 volumio volumio[926]: info: Loading plugin "i2s_dacs"... Mar 14 16:15:07 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 14 16:15:07 volumio volumio[926]: info: Loading plugin "volumiodiscovery"... Mar 14 16:15:07 volumio volumio[926]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 14 16:15:07 volumio node[926]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 14 16:15:07 volumio node[926]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 14 16:15:07 volumio node[926]: *** WARNING *** For more information see Mar 14 16:15:07 volumio node[926]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 14 16:15:07 volumio node[926]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 14 16:15:07 volumio node[926]: *** WARNING *** For more information see Mar 14 16:15:07 volumio volumio[926]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 14 16:15:07 volumio volumio[926]: *** WARNING *** For more information see Mar 14 16:15:07 volumio volumio[926]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 14 16:15:07 volumio volumio[926]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 14 16:15:07 volumio volumio[926]: *** WARNING *** For more information see Mar 14 16:15:07 volumio volumio[926]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 14 16:15:07 volumio volumio[926]: Discovery: StartAdv! undefined Mar 14 16:15:07 volumio volumio[926]: Discovery: Started advertising... Volumio - undefined Mar 14 16:15:07 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 14 16:15:07 volumio volumio[926]: info: Plugin volsimpleequal is not enabled Mar 14 16:15:07 volumio volumio[926]: info: Loading plugin "albumart"... Mar 14 16:15:07 volumio volumio[926]: info: Plugin example_plugin is not enabled Mar 14 16:15:07 volumio volumio[926]: info: Loading plugin "updater_comm"... Mar 14 16:15:08 volumio volumio[926]: info: Plugin mpdemulation is not enabled Mar 14 16:15:08 volumio volumio[926]: info: Loading plugin "rest_api"... Mar 14 16:15:08 volumio volumio[926]: info: Loading plugin "websocket"... Mar 14 16:15:08 volumio volumio[926]: info: Plugin spop is not enabled Mar 14 16:15:08 volumio volumio[926]: info: Loading plugin "volspotconnect2"... Mar 14 16:15:08 volumio volumio[926]: Forking 3 albumart workers Mar 14 16:15:09 volumio volumio[926]: Starting albumart workers Mar 14 16:15:09 volumio volumio[926]: Starting albumart workers Mar 14 16:15:10 volumio volumio[926]: Starting albumart workers Mar 14 16:15:10 volumio dhcpcd[485]: timed out Mar 14 16:15:10 volumio dhcpcd[485]: exited Mar 14 16:15:10 volumio ifup[464]: dhcpcd[485]: timed out Mar 14 16:15:10 volumio ifup[464]: dhcpcd[485]: exited Mar 14 16:15:10 volumio ifup[464]: Failed to bring up eth0. Mar 14 16:15:13 volumio sudo[950]: pam_unix(sudo:session): session closed for user root Mar 14 16:15:13 volumio sudo[952]: pam_unix(sudo:session): session closed for user root Mar 14 16:15:13 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Mar 14 16:15:13 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Mar 14 16:15:13 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -113 Mar 14 16:15:13 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -113 Mar 14 16:15:13 volumio volumio[926]: info: ___________ START PLUGINS ___________ Mar 14 16:15:13 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 14 16:15:13 volumio volumio[926]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 14 16:15:13 volumio volumio[926]: info: [1741968913097] CoreMusicLibrary::Adding element Media Servers Mar 14 16:15:13 volumio volumio[926]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 14 16:15:13 volumio volumio[926]: info: [1741968913125] CoreMusicLibrary::Adding element Last_100 Mar 14 16:15:13 volumio volumio[926]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 14 16:15:13 volumio volumio[926]: info: [1741968913129] CoreMusicLibrary::Adding element Webradio Mar 14 16:15:13 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 14 16:15:13 volumio volumio[926]: [SpotifyConnect] Creating VLS config file Mar 14 16:15:13 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 14 16:15:13 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 14 16:15:13 volumio volumio[926]: [SpotifyConnect] <> or Mixer found, using softvol Mar 14 16:15:13 volumio volumio[926]: [SpotifyConnect] Starting metadata listener Mar 14 16:15:13 volumio volumio[926]: info: ------------------------------------------- Mar 14 16:15:13 volumio volumio[926]: info: ----- MyVolumio plugins startup ---- Mar 14 16:15:13 volumio volumio[926]: info: ------------------------------------------- Mar 14 16:15:13 volumio volumio[926]: info: Loading plugins from folder /myvolumio/plugins Mar 14 16:15:13 volumio volumio[926]: info: Loading plugins from folder /data/myvolumio/plugins Mar 14 16:15:13 volumio volumio[926]: info: Loading i18n strings for locale fr Mar 14 16:15:13 volumio sudo[1007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Mar 14 16:15:13 volumio volumio[926]: Updating browse sources language Mar 14 16:15:13 volumio sudo[1007]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 14 16:15:13 volumio volumio[926]: Cannot find translation for sourceMedia Servers Mar 14 16:15:13 volumio systemd[1]: Starting Volspotconnect2 Daemon... Mar 14 16:15:13 volumio systemd[1]: Started Volspotconnect2 Daemon. Mar 14 16:15:13 volumio sudo[1007]: pam_unix(sudo:session): session closed for user root Mar 14 16:15:13 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 14 16:15:13 volumio volumio[926]: info: CoreCommandRouter::initPlayerControls Mar 14 16:15:13 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 14 16:15:13 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Mar 14 16:15:13 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 14 16:15:13 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 14 16:15:13 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 14 16:15:13 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 14 16:15:13 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 14 16:15:13 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 14 16:15:13 volumio volumio[926]: info: BOOT COMPLETED Mar 14 16:15:13 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 14 16:15:13 volumio volumio[1012]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Mar 14 16:15:13 volumio volumio[1012]: Reading Config from "volspotify.toml" Mar 14 16:15:14 volumio volumio[1012]: [Vollibrespot] : Using Alsa backend with device: plughw:1 Mar 14 16:15:14 volumio volumio-remote-updater[530]: [2025-03-14 16:15:14] [connect] Successful connection Mar 14 16:15:14 volumio volumio[1012]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Mar 14 16:15:19 volumio volumio[926]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Mar 14 16:15:19 volumio volumio[926]: info: CoreCommandRouter::Close All Modals sent Mar 14 16:15:19 volumio volumio[926]: info: CoreCommandRouter::Close All Modals sent Mar 14 16:15:19 volumio volumio[926]: Express server listening on port 3000 Mar 14 16:15:19 volumio volumio[926]: Volumio Calling Home Mar 14 16:15:19 volumio volumio[926]: info: CoreStateMachine::resetVolumioState Mar 14 16:15:19 volumio volumio[926]: info: CoreStateMachine::getcurrentVolume Mar 14 16:15:19 volumio volumio[926]: info: CoreCommandRouter::volumioRetrievevolume Mar 14 16:15:19 volumio volumio[926]: info: CoreStateMachine::updateTrackBlock Mar 14 16:15:19 volumio volumio[926]: info: CorePlayQueue::getTrackBlock Mar 14 16:15:19 volumio volumio[926]: info: Setting Device type: Raspberry PI Mar 14 16:15:19 volumio volumio[926]: [SpotifyConnect] Vollibrespot Daemon service started! Mar 14 16:15:19 volumio volumio[926]: SpotifyConnect: 6466.587ms Mar 14 16:15:19 volumio volumio[926]: info: Cannot mount NAS classique-pc at system boot, trial number 1 ,retrying in 5 seconds Mar 14 16:15:19 volumio volumio[926]: info: Cannot mount NAS musique-pc at system boot, trial number 1 ,retrying in 5 seconds Mar 14 16:15:19 volumio volumio[926]: info: MPD running with PID558 ,establishing connection Mar 14 16:15:19 volumio volumio[926]: info: VolumeController:: Volume=undefined Mute =undefined Mar 14 16:15:19 volumio volumio[926]: info: CoreStateMachine::pushState Mar 14 16:15:19 volumio volumio[926]: info: CoreStateMachine::getState Mar 14 16:15:19 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Mar 14 16:15:19 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Mar 14 16:15:19 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 16:15:19 volumio volumio[926]: info: Mar 14 16:15:19 volumio volumio[926]: ---------------------------- Client requests Volume 100 Mar 14 16:15:19 volumio volumio[926]: info: VolumeController::SetAlsaVolume100 Mar 14 16:15:20 volumio volumio[926]: info: Reloading queue from file Mar 14 16:15:20 volumio volumio[926]: info: Mar 14 16:15:20 volumio volumio[926]: info: message= [50@0] {} No such directory, stack=Error: [50@0] {} No such directory Mar 14 16:15:20 volumio volumio[926]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14) Mar 14 16:15:20 volumio volumio[926]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10) Mar 14 16:15:20 volumio volumio[926]: at emitOne (events.js:116:13) Mar 14 16:15:20 volumio volumio[926]: at Socket.emit (events.js:211:7) Mar 14 16:15:20 volumio volumio[926]: at addChunk (_stream_readable.js:263:12) Mar 14 16:15:20 volumio volumio[926]: at readableAddChunk (_stream_readable.js:246:13) Mar 14 16:15:20 volumio volumio[926]: at Socket.Readable.push (_stream_readable.js:208:10) Mar 14 16:15:20 volumio volumio[926]: at Pipe.onread (net.js:607:20) Mar 14 16:15:20 volumio volumio[926]: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Mar 14 16:15:20 volumio volumio[926]: at Socket. (/volumio/app/volumecontrol.js:72:10) Mar 14 16:15:20 volumio volumio[926]: at emitOne (events.js:116:13) Mar 14 16:15:20 volumio volumio[926]: at Socket.emit (events.js:211:7) Mar 14 16:15:20 volumio volumio[926]: at addChunk (_stream_readable.js:263:12) Mar 14 16:15:20 volumio volumio[926]: at readableAddChunk (_stream_readable.js:250:11) Mar 14 16:15:20 volumio volumio[926]: at Socket.Readable.push (_stream_readable.js:208:10) Mar 14 16:15:20 volumio volumio[926]: at Pipe.onread (net.js:607:20) Mar 14 16:15:20 volumio volumio[926]: info: VolumeController::Volume 100 Mar 14 16:15:20 volumio volumio[926]: info: CoreStateMachine::pushState Mar 14 16:15:20 volumio volumio[926]: info: CoreStateMachine::getState Mar 14 16:15:20 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Mar 14 16:15:20 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Mar 14 16:15:20 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 16:15:20 volumio volumio[926]: info: Setting volume on startup at 100 Mar 14 16:15:20 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 14 16:15:20 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 14 16:15:20 volumio volumio[926]: info: CoreStateMachine::setRepeat null single undefined Mar 14 16:15:20 volumio volumio[926]: info: CoreStateMachine::pushState Mar 14 16:15:20 volumio volumio[926]: info: CoreStateMachine::getState Mar 14 16:15:20 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Mar 14 16:15:20 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Mar 14 16:15:20 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 16:15:20 volumio volumio[926]: info: CoreStateMachine::setRandom null Mar 14 16:15:20 volumio volumio[926]: info: CoreStateMachine::pushState Mar 14 16:15:20 volumio volumio[926]: info: CoreStateMachine::getState Mar 14 16:15:20 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Mar 14 16:15:20 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Mar 14 16:15:20 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 16:15:20 volumio sudo[1040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 14 16:15:20 volumio sudo[1042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Mar 14 16:15:20 volumio sudo[1040]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 14 16:15:20 volumio sudo[1042]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 14 16:15:20 volumio volumio[926]: Discovery: adding ad41747f-cb1f-4450-97cf-c6b0db15772a Mar 14 16:15:20 volumio volumio[926]: info: mDNS: Found device Volumio Mar 14 16:15:20 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Mar 14 16:15:20 volumio systemd[1]: Started ShairportSync AirTunes receiver. Mar 14 16:15:20 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Mar 14 16:15:20 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Mar 14 16:15:20 volumio sudo[1040]: pam_unix(sudo:session): session closed for user root Mar 14 16:15:20 volumio sudo[1042]: pam_unix(sudo:session): session closed for user root Mar 14 16:15:20 volumio shairport-sync[1052]: Successful Startup Mar 14 16:15:20 volumio volumio[926]: info: [1741968920667] Shairport-Sync Started Mar 14 16:15:20 volumio volumio[926]: Error adding Membership: Error: addMembership EINVAL Mar 14 16:15:20 volumio volumio[926]: info: Upmpdcli Daemon Started Mar 14 16:15:20 volumio volumio[926]: info: CoreCommandRouter::volumioGetState Mar 14 16:15:20 volumio volumio[926]: info: CoreStateMachine::getState Mar 14 16:15:20 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Mar 14 16:15:26 volumio sudo[1068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=jerome,password=tsttst,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.55/ /mnt/NAS/classique-pc Mar 14 16:15:26 volumio sudo[1068]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 14 16:15:26 volumio sudo[1070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=jerome,password=tsttst,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.55/ /mnt/NAS/musique-pc Mar 14 16:15:26 volumio sudo[1070]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 14 16:15:26 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Mar 14 16:15:26 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Mar 14 16:15:28 volumio volumio[926]: info: CoreCommandRouter::volumioStop Mar 14 16:15:28 volumio volumio[926]: info: CoreStateMachine::stop Mar 14 16:15:28 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 14 16:15:28 volumio volumio[926]: UNSET VOLATILE Mar 14 16:15:28 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 14 16:15:28 volumio volumio[926]: info: Airplay started streaming Mar 14 16:15:29 volumio volumio-remote-updater[530]: [2025-03-14 16:15:29] [connect] Successful connection Mar 14 16:15:29 volumio volumio[926]: info: CoreCommandRouter::servicePushState Mar 14 16:15:30 volumio volumio[926]: info: CoreStateMachine::syncState Mar 14 16:15:30 volumio volumio[926]: info: CoreStateMachine::pushState Mar 14 16:15:30 volumio volumio[926]: info: CoreStateMachine::getState Mar 14 16:15:30 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Mar 14 16:15:30 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 16:15:30 volumio volumio[926]: info: CoreCommandRouter::servicePushState Mar 14 16:15:30 volumio volumio[926]: info: CoreStateMachine::syncState Mar 14 16:15:30 volumio volumio[926]: info: CoreStateMachine::pushState Mar 14 16:15:30 volumio volumio[926]: info: CoreStateMachine::getState Mar 14 16:15:30 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Mar 14 16:15:30 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 16:15:30 volumio volumio[926]: info: CoreCommandRouter::servicePushState Mar 14 16:15:30 volumio volumio[926]: info: CoreStateMachine::syncState Mar 14 16:15:30 volumio volumio[926]: info: CoreStateMachine::pushState Mar 14 16:15:30 volumio volumio[926]: info: CoreStateMachine::getState Mar 14 16:15:30 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Mar 14 16:15:30 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 16:15:30 volumio volumio[926]: info: CoreCommandRouter::servicePushState Mar 14 16:15:30 volumio volumio[926]: info: CoreStateMachine::syncState Mar 14 16:15:30 volumio volumio[926]: info: CoreStateMachine::pushState Mar 14 16:15:30 volumio volumio[926]: info: CoreStateMachine::getState Mar 14 16:15:30 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Mar 14 16:15:30 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 16:15:30 volumio volumio[926]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.444&uuid=87dce84f53b46e64129e1126bd88c944" http://updates.volumio.org:7070/downloader-v1/track-device Mar 14 16:15:30 volumio volumio[926]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 14 16:15:30 volumio volumio[926]: Dload Upload Total Spent Left Speed Mar 14 16:15:30 volumio volumio[926]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Mar 14 16:15:30 volumio volumio[926]: retrying in 5 seconds, trial 0 Mar 14 16:15:30 volumio volumio[926]: Volumio Calling Home Mar 14 16:15:30 volumio volumio-remote-updater[530]: [2025-03-14 16:15:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1741968929 101 Mar 14 16:15:32 volumio sudo[1070]: pam_unix(sudo:session): session closed for user root Mar 14 16:15:32 volumio sudo[1068]: pam_unix(sudo:session): session closed for user root Mar 14 16:15:32 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Mar 14 16:15:32 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Mar 14 16:15:32 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -113 Mar 14 16:15:32 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -113 Mar 14 16:15:35 volumio volumio[926]: info: Cannot mount NAS musique-pc at system boot, trial number 2 ,retrying in 5 seconds Mar 14 16:15:35 volumio volumio[926]: info: Cannot mount NAS classique-pc at system boot, trial number 2 ,retrying in 5 seconds Mar 14 16:15:40 volumio sudo[1121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=jerome,password=tsttst,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.55/ /mnt/NAS/musique-pc Mar 14 16:15:40 volumio sudo[1121]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 14 16:15:40 volumio sudo[1124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=jerome,password=tsttst,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.55/ /mnt/NAS/classique-pc Mar 14 16:15:40 volumio sudo[1124]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 14 16:15:40 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Mar 14 16:15:40 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Mar 14 16:15:42 volumio volumio[926]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.444&uuid=87dce84f53b46e64129e1126bd88c944" http://updates.volumio.org:7070/downloader-v1/track-device Mar 14 16:15:42 volumio volumio[926]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 14 16:15:42 volumio volumio[926]: Dload Upload Total Spent Left Speed Mar 14 16:15:42 volumio volumio[926]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Mar 14 16:15:42 volumio volumio[926]: retrying in 5 seconds, trial 1 Mar 14 16:15:42 volumio volumio[926]: Volumio Calling Home Mar 14 16:15:43 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 14 16:15:43 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 14 16:15:46 volumio volumio[926]: info: CoreCommandRouter::servicePushState Mar 14 16:15:46 volumio volumio[926]: info: CoreStateMachine::syncState Mar 14 16:15:46 volumio volumio[926]: info: CoreStateMachine::pushState Mar 14 16:15:46 volumio volumio[926]: info: CoreStateMachine::getState Mar 14 16:15:46 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Mar 14 16:15:46 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 16:15:46 volumio volumio[926]: info: CoreCommandRouter::servicePushState Mar 14 16:15:46 volumio volumio[926]: info: CoreStateMachine::syncState Mar 14 16:15:46 volumio volumio[926]: info: CoreStateMachine::pushState Mar 14 16:15:46 volumio volumio[926]: info: CoreStateMachine::getState Mar 14 16:15:46 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Mar 14 16:15:46 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 16:15:46 volumio sudo[1121]: pam_unix(sudo:session): session closed for user root Mar 14 16:15:46 volumio sudo[1124]: pam_unix(sudo:session): session closed for user root Mar 14 16:15:46 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Mar 14 16:15:46 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Mar 14 16:15:46 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -113 Mar 14 16:15:46 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -113 Mar 14 16:15:48 volumio volumio[926]: info: Cannot mount NAS musique-pc at system boot, trial number 3 ,retrying in 5 seconds Mar 14 16:15:48 volumio volumio[926]: info: Cannot mount NAS classique-pc at system boot, trial number 3 ,retrying in 5 seconds Mar 14 16:15:56 volumio sudo[1156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=jerome,password=tsttst,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.55/ /mnt/NAS/musique-pc Mar 14 16:15:56 volumio sudo[1156]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 14 16:15:56 volumio volumio[926]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.444&uuid=87dce84f53b46e64129e1126bd88c944" http://updates.volumio.org:7070/downloader-v1/track-device Mar 14 16:15:56 volumio volumio[926]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 14 16:15:56 volumio volumio[926]: Dload Upload Total Spent Left Speed Mar 14 16:15:56 volumio volumio[926]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Mar 14 16:15:56 volumio volumio[926]: retrying in 5 seconds, trial 2 Mar 14 16:15:56 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Mar 14 16:15:56 volumio volumio[926]: Volumio Calling Home Mar 14 16:15:56 volumio sudo[1160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=jerome,password=tsttst,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.55/ /mnt/NAS/classique-pc Mar 14 16:15:56 volumio sudo[1160]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 14 16:15:56 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Mar 14 16:16:02 volumio sudo[1156]: pam_unix(sudo:session): session closed for user root Mar 14 16:16:02 volumio sudo[1160]: pam_unix(sudo:session): session closed for user root Mar 14 16:16:02 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Mar 14 16:16:02 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -113 Mar 14 16:16:02 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Mar 14 16:16:02 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -113 Mar 14 16:16:02 volumio volumio[926]: info: Cannot mount NAS musique-pc at system boot, trial number 4 ,retrying in 5 seconds Mar 14 16:16:02 volumio volumio[926]: info: Cannot mount NAS classique-pc at system boot, trial number 4 ,retrying in 5 seconds Mar 14 16:16:03 volumio volumio[926]: info: CoreCommandRouter::servicePushState Mar 14 16:16:03 volumio volumio[926]: info: CoreStateMachine::syncState Mar 14 16:16:03 volumio volumio[926]: info: CoreStateMachine::pushState Mar 14 16:16:03 volumio volumio[926]: info: CoreStateMachine::getState Mar 14 16:16:03 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Mar 14 16:16:03 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 16:16:03 volumio volumio[926]: info: CoreCommandRouter::servicePushState Mar 14 16:16:03 volumio volumio[926]: info: CoreStateMachine::syncState Mar 14 16:16:03 volumio volumio[926]: info: CoreStateMachine::pushState Mar 14 16:16:03 volumio volumio[926]: info: CoreStateMachine::getState Mar 14 16:16:03 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Mar 14 16:16:03 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 16:16:07 volumio sudo[1197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=jerome,password=tsttst,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.55/ /mnt/NAS/musique-pc Mar 14 16:16:07 volumio sudo[1197]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 14 16:16:07 volumio sudo[1201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=jerome,password=tsttst,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.55/ /mnt/NAS/classique-pc Mar 14 16:16:07 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Mar 14 16:16:07 volumio sudo[1201]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 14 16:16:07 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Mar 14 16:16:13 volumio sudo[1197]: pam_unix(sudo:session): session closed for user root Mar 14 16:16:13 volumio sudo[1201]: pam_unix(sudo:session): session closed for user root Mar 14 16:16:13 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Mar 14 16:16:13 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -113 Mar 14 16:16:13 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -113 Mar 14 16:16:13 volumio volumio[926]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Mar 14 16:16:13 volumio volumio[926]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Mar 14 16:16:13 volumio volumio[926]: info: CoreCommandRouter::servicePushState Mar 14 16:16:13 volumio volumio[926]: info: CoreStateMachine::syncState Mar 14 16:16:13 volumio volumio[926]: info: CoreStateMachine::pushState Mar 14 16:16:13 volumio volumio[926]: info: CoreStateMachine::getState Mar 14 16:16:13 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Mar 14 16:16:13 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 16:16:14 volumio volumio[926]: info: CoreCommandRouter::servicePushState Mar 14 16:16:14 volumio volumio[926]: info: CoreStateMachine::syncState Mar 14 16:16:14 volumio volumio[926]: info: CoreStateMachine::pushState Mar 14 16:16:14 volumio volumio[926]: info: CoreStateMachine::getState Mar 14 16:16:14 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Mar 14 16:16:14 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 16:16:21 volumio shairport-sync[1052]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 14 16:16:21 volumio shairport-sync[1052]: Dload Upload Total Spent Left Speed Mar 14 16:16:21 volumio volumio[926]: info: Mar 14 16:16:21 volumio volumio[926]: ---------------------------- Client requests Start Airplay metadata parsing Mar 14 16:16:21 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , airPlayStop Mar 14 16:16:21 volumio volumio[926]: UNSET VOLATILE Mar 14 16:16:21 volumio volumio[926]: info: CoreStateMachine::resetVolumioState Mar 14 16:16:21 volumio volumio[926]: info: CoreStateMachine::getcurrentVolume Mar 14 16:16:21 volumio volumio[926]: info: CoreCommandRouter::volumioRetrievevolume Mar 14 16:16:21 volumio volumio[926]: info: CoreStateMachine::updateTrackBlock Mar 14 16:16:21 volumio volumio[926]: info: CorePlayQueue::getTrackBlock Mar 14 16:16:21 volumio volumio[926]: info: CoreCommandRouter::volumioStop Mar 14 16:16:21 volumio volumio[926]: info: CoreStateMachine::stop Mar 14 16:16:21 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 14 16:16:21 volumio volumio[926]: UNSET VOLATILE Mar 14 16:16:21 volumio shairport-sync[1052]: [155B blob data] Mar 14 16:16:21 volumio volumio[926]: info: VolumeController:: Volume=undefined Mute =undefined Mar 14 16:16:21 volumio volumio[926]: info: CoreStateMachine::pushState Mar 14 16:16:21 volumio volumio[926]: info: CoreStateMachine::getState Mar 14 16:16:21 volumio volumio[926]: info: CorePlayQueue::getTrack 0 Mar 14 16:16:21 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Mar 14 16:16:21 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 16:16:24 volumio volumio[926]: info: CoreCommandRouter::volumioStop Mar 14 16:16:24 volumio volumio[926]: info: CoreStateMachine::stop Mar 14 16:16:24 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 14 16:16:24 volumio volumio[926]: UNSET VOLATILE Mar 14 16:16:24 volumio volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 14 16:16:24 volumio volumio[926]: info: Airplay started streaming Mar 14 16:16:26 volumio volumio[926]: info: CoreCommandRouter::servicePushState Mar 14 16:16:26 volumio volumio[926]: info: CoreStateMachine::syncState Mar 14 16:16:26 volumio volumio[926]: info: CoreStateMachine::pushState Mar 14 16:16:26 volumio volumio[926]: info: CoreStateMachine::getState Mar 14 16:16:26 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Mar 14 16:16:26 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 16:16:26 volumio volumio[926]: info: CoreCommandRouter::servicePushState Mar 14 16:16:26 volumio volumio[926]: info: CoreStateMachine::syncState Mar 14 16:16:26 volumio volumio[926]: info: CoreStateMachine::pushState Mar 14 16:16:26 volumio volumio[926]: info: CoreStateMachine::getState Mar 14 16:16:26 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Mar 14 16:16:26 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 16:16:46 volumio volumio[926]: info: CoreCommandRouter::servicePushState Mar 14 16:16:46 volumio volumio[926]: info: CoreStateMachine::syncState Mar 14 16:16:46 volumio volumio[926]: info: CoreStateMachine::pushState Mar 14 16:16:46 volumio volumio[926]: info: CoreStateMachine::getState Mar 14 16:16:46 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Mar 14 16:16:46 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 16:16:46 volumio volumio[926]: info: CoreCommandRouter::servicePushState Mar 14 16:16:46 volumio volumio[926]: info: CoreStateMachine::syncState Mar 14 16:16:46 volumio volumio[926]: info: CoreStateMachine::pushState Mar 14 16:16:46 volumio volumio[926]: info: CoreStateMachine::getState Mar 14 16:16:46 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Mar 14 16:16:46 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 16:16:52 volumio volumio[1012]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" Mar 14 16:16:52 volumio volumio[1012]: [Vollibrespot] : Authenticated as "i5pr6nn9fdqpr9zggocjvxc4h" ! Mar 14 16:16:52 volumio volumio[1012]: [Vollibrespot] : Using alsa sink Mar 14 16:16:52 volumio volumio[1012]: [Vollibrespot] : Metadata pipe established Mar 14 16:16:52 volumio volumio[1012]: [Vollibrespot] : Event: Volume { volume_to_mixer: 21626 } Mar 14 16:16:52 volumio volumio[1012]: [Vollibrespot] : Country: "GB" Mar 14 16:16:52 volumio volumio[926]: [SpotifyConnect] 32.99916075379568 Mar 14 16:16:52 volumio volumio[926]: [SpotifyConnect] Volume: Spotify:32.99916075379568 Volumio: 33 Mar 14 16:16:52 volumio volumio[926]: info: CoreStateMachine::pushState Mar 14 16:16:52 volumio volumio[926]: info: CoreStateMachine::getState Mar 14 16:16:52 volumio volumio[926]: info: CoreCommandRouter::volumioPushState Mar 14 16:16:52 volumio volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 16:16:53 volumio volumio[1012]: [Vollibrespot] : Event: SessionActive { became_active_at: 1741969013059 } Mar 14 16:16:53 volumio volumio[1012]: [Vollibrespot] : SessionActive! Mar 14 16:16:53 volumio volumio[1012]: [Vollibrespot] : Fetching autoplay context uri Mar 14 16:16:53 volumio volumio[1012]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 27779407447703235113752118409894347959, audio_type: Track } } Mar 14 16:16:53 volumio volumio[1012]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 27779407447703235113752118409894347959, audio_type: Track } } Mar 14 16:16:53 volumio volumio[926]: [SpotifyConnect] A connect session has begun Mar 14 16:16:53 volumio volumio[926]: [SpotifyConnect] Device palyback is active! Mar 14 16:16:53 volumio volumio[926]: info: CoreCommandRouter::volumioGetState Mar 14 16:16:53 volumio volumio[926]: info: CoreStateMachine::getState Mar 14 16:16:53 volumio volumio[926]: [SpotifyConnect] Currently active: airplay Mar 14 16:16:53 volumio volumio[926]: [SpotifyConnect] Stopping currently active service Mar 14 16:16:53 volumio volumio[926]: info: CoreCommandRouter::volumioStop Mar 14 16:16:53 volumio volumio[926]: info: CoreStateMachine::stop Mar 14 16:16:53 volumio volumio[926]: info: CoreStateMachine::serviceStop Mar 14 16:16:53 volumio volumio[926]: info: CoreCommandRouter::serviceStop Mar 14 16:16:53 volumio volumio[926]: error: WARNING: No stop method for service airplay Mar 14 16:16:53 volumio volumio[926]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 14 16:16:53 volumio volumio[926]: TypeError: Cannot read property 'then' of undefined Mar 14 16:16:53 volumio volumio[926]: at SpotConnEvents.SpotConn.on (/data/plugins/music_service/volspotconnect2/index.js:143:23) Mar 14 16:16:53 volumio volumio[926]: at emitNone (events.js:106:13) Mar 14 16:16:53 volumio volumio[926]: at SpotConnEvents.emit (events.js:208:7) Mar 14 16:16:53 volumio volumio[926]: at SpotConnEvents.parseData (/data/plugins/music_service/volspotconnect2/SpotConnController.js:73:16) Mar 14 16:16:53 volumio volumio[926]: at Socket.SpotConnEvents._udpsource.on.msg (/data/plugins/music_service/volspotconnect2/SpotConnController.js:15:12) Mar 14 16:16:53 volumio volumio[926]: at emitTwo (events.js:126:13) Mar 14 16:16:53 volumio volumio[926]: at Socket.emit (events.js:214:7) Mar 14 16:16:53 volumio volumio[926]: at UDP.onMessage [as onmessage] (dgram.js:659:8) Mar 14 16:16:53 volumio volumio[926]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 14 16:16:53 volumio volumio[1012]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:37i9dQZF1EP6YuccBxUcC1"> Mar 14 16:16:53 volumio volumio[1012]: [Vollibrespot] : Resolving uri "spotify:station:playlist:37i9dQZF1EP6YuccBxUcC1" Mar 14 16:16:53 volumio volumio[1012]: [Vollibrespot] : Loading with Spotify URI Mar 14 16:16:53 volumio volumio[1012]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQA60ToWWUpjNINjIH5xJDtuUGABc0u7ge4f5jyEvmOT5frMKk1wi3oCa4PEUnObtlufcCrB48aFinOFBOLTT8kpySDziMjCypV1XoOz7Y-sID0z1jtVqoXF2OIbNCGsBlW21CdLKUvwTirp51mS3GpHghzGQ6Sch996-F3ZKC4IqrUxA7FcwYI_2Jgn5xaSNeZgumSnFBp2IN6jRzLjoiYJSBN3xw5JjhIpEZeYovUpV8bAJrFzCXlPrg", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Mar 14 16:16:53 volumio volumio[1012]: [Vollibrespot] : Reducing normalisation factor to prevent clipping. Please add negative pregain to avoid. Mar 14 16:16:53 volumio volumio[1012]: [Vollibrespot] : Resolved 50 tracks from <"spotify:playlist:37i9dQZF1EP6YuccBxUcC1"> Mar 14 16:16:54 volumio volumio[1012]: [Vollibrespot] : (231834 ms) loaded Mar 14 16:16:54 volumio volumio[1012]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Mar 14 16:16:54 volumio volumio[1012]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Mar 14 16:16:54 volumio volumio[1012]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Mar 14 16:16:54 volumio volumio[1012]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Mar 14 16:16:54 volumio volumio[1012]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Mar 14 16:16:54 volumio volumio[1012]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Mar 14 16:16:54 volumio volumio[1012]: thread '' panicked at 'called `Option::unwrap()` on a `None` value', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/playback/src/audio_backend/alsa.rs:129:35 Mar 14 16:16:54 volumio volumio[1012]: stack backtrace: Mar 14 16:16:54 volumio volumio[1012]: 0: 0xa5233c - backtrace::backtrace::libunwind::trace::hae21a072c81e5842 Mar 14 16:16:54 volumio volumio[1012]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86 Mar 14 16:16:54 volumio volumio[1012]: 1: 0xa5233c - backtrace::backtrace::trace_unsynchronized::h0f9b260087e46e47 Mar 14 16:16:54 volumio volumio[1012]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66 Mar 14 16:16:54 volumio volumio[1012]: 2: 0xa5233c - std::sys_common::backtrace::_print_fmt::hbf1a59173a7860c3 Mar 14 16:16:54 volumio volumio[1012]: at src/libstd/sys_common/backtrace.rs:78 Mar 14 16:16:54 volumio volumio[1012]: 3: 0xa5233c - ::fmt::he1a5d6f378e506c4 Mar 14 16:16:54 volumio volumio[1012]: at src/libstd/sys_common/backtrace.rs:59 Mar 14 16:16:54 volumio volumio[1012]: 4: 0xa72d5c - core::fmt::write::hb37ae5a5e0b70623 Mar 14 16:16:54 volumio volumio[1012]: at src/libcore/fmt/mod.rs:1076 Mar 14 16:16:54 volumio volumio[1012]: 5: 0xa4bc64 - std::io::Write::write_fmt::ha24bb3f5a858327b Mar 14 16:16:54 volumio volumio[1012]: at src/libstd/io/mod.rs:1537 Mar 14 16:16:54 volumio volumio[1012]: 6: 0xa54974 - std::sys_common::backtrace::_print::h47b03aa1342833e3 Mar 14 16:16:54 volumio volumio[1012]: at src/libstd/sys_common/backtrace.rs:62 Mar 14 16:16:54 volumio volumio[1012]: 7: 0xa54974 - std::sys_common::backtrace::print::h2217cbc390250439 Mar 14 16:16:54 volumio volumio[1012]: at src/libstd/sys_common/backtrace.rs:49 Mar 14 16:16:54 volumio volumio[1012]: 8: 0xa54974 - std::panicking::default_hook::{{closure}}::h179f5229ea1c8e30 Mar 14 16:16:54 volumio volumio[1012]: at src/libstd/panicking.rs:198 Mar 14 16:16:54 volumio volumio[1012]: 9: 0xa54640 - std::panicking::default_hook::h46ab82039cbc65eb Mar 14 16:16:54 volumio volumio[1012]: at src/libstd/panicking.rs:217 Mar 14 16:16:54 volumio volumio[1012]: 10: 0xa55054 - std::panicking::rust_panic_with_hook::h7326c48419bc7c33 Mar 14 16:16:54 volumio volumio[1012]: at src/libstd/panicking.rs:526 Mar 14 16:16:54 volumio volumio[1012]: 11: 0xa54c54 - rust_begin_unwind Mar 14 16:16:54 volumio volumio[1012]: at src/libstd/panicking.rs:437 Mar 14 16:16:54 volumio volumio[1012]: 12: 0xa70594 - core::panicking::panic_fmt::ha292e19d5ae716ed Mar 14 16:16:54 volumio volumio[1012]: at src/libcore/panicking.rs:85 Mar 14 16:16:54 volumio volumio[1012]: 13: 0xa704f0 - core::panicking::panic::heb457f4fa750842b Mar 14 16:16:54 volumio volumio[1012]: at src/libcore/panicking.rs:50 Mar 14 16:16:54 volumio volumio[1012]: 14: 0x64fee8 - ::write::h5da62cd8da1289c3 Mar 14 16:16:54 volumio volumio[1012]: 15: 0x6293c4 - ::poll::h2bafd5dc8d40bf3f Mar 14 16:16:54 volumio volumio[1012]: 16: 0x539eec - futures::task_impl::std::set::h2116cb0e9a5165fe Mar 14 16:16:54 volumio volumio[1012]: 17: 0x52ec6c - futures::task_impl::Spawn::poll_future_notify::h20a5cc82ac41f2fa Mar 14 16:16:54 volumio volumio[1012]: 18: 0x5486a0 - futures::future::Future::wait::ha8819dd7a48bc248 Mar 14 16:16:54 volumio volumio[1012]: 19: 0x5304b4 - std::sys_common::backtrace::__rust_begin_short_backtrace::habfd768f2a80dc30 Mar 14 16:16:54 volumio volumio[1012]: 20: 0x54f5a8 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h0dcfa92cb8b4ff47 Mar 14 16:16:54 volumio volumio[1012]: 21: 0xa5846c - as core::ops::function::FnOnce>::call_once::hb2bcc0b96e754d10 Mar 14 16:16:54 volumio volumio[1012]: at /rustc/04488afe34512aa4c33566eb16d8c912a3ae04f9/src/liballoc/boxed.rs:1081 Mar 14 16:16:54 volumio volumio[1012]: 22: 0xa5846c - as core::ops::function::FnOnce>::call_once::h2dd73f7c66408260 Mar 14 16:16:54 volumio volumio[1012]: at /rustc/04488afe34512aa4c33566eb16d8c912a3ae04f9/src/liballoc/boxed.rs:1081 Mar 14 16:16:54 volumio volumio[1012]: 23: 0xa5846c - std::sys::unix::thread::Thread::new::thread_start::h6fc0046e32ed87dc Mar 14 16:16:54 volumio volumio[1012]: at src/libstd/sys/unix/thread.rs:87 Mar 14 16:16:55 volumio sudo[1305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-14 16:15 Mar 14 16:16:55 volumio sudo[1305]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="b38474a34dd6501a9ca2801645a6a861fdbc549d" VOLUMIO_FE_VERSION="f027ce75738c64189018057558e04cba7a74a080" VOLUMIO_BE_VERSION="a98512120222cc847d1586e8d2068e08cb058c79" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Jul 26 16:22:33 CEST 2018" VOLUMIO_VERSION="2.444" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="7f1396787ccc441a3d64fa9fc7c6c303"