-- Logs begin at Sun 2025-05-04 11:29:14 UTC, end at Sun 2025-05-04 13:02:46 UTC. -- May 04 13:02:39 volumio systemd[1]: Time has been changed May 04 13:02:39 volumio systemd[1]: volspotconnect.service holdoff time over, scheduling restart. May 04 13:02:39 volumio systemd[1]: Stopping Volspotconnect2 Daemon... May 04 13:02:39 volumio systemd[1]: Starting Volspotconnect2 Daemon... May 04 13:02:39 volumio systemd[1]: Started Volspotconnect2 Daemon. May 04 13:02:39 volumio volumio[937]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 May 04 13:02:39 volumio volumio[937]: Reading Config from "/tmp/volspotify.toml" May 04 13:02:39 volumio volumio[937]: Malformed config key: missing field `Authentication` May 04 13:02:39 volumio volumio[841]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo May 04 13:02:39 volumio volumio[841]: info: CoreCommandRouter::Close All Modals sent May 04 13:02:39 volumio volumio[841]: info: CoreCommandRouter::Close All Modals sent May 04 13:02:39 volumio volumio[841]: Express server listening on port 3000 May 04 13:02:39 volumio volumio[841]: Volumio Calling Home May 04 13:02:39 volumio volumio[841]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 May 04 13:02:39 volumio volumio[841]: at Object._errnoException (util.js:1022:11) May 04 13:02:39 volumio volumio[841]: at _exceptionWithHostPort (util.js:1044:20) May 04 13:02:39 volumio volumio[841]: at _handle.lookup (dgram.js:266:18) May 04 13:02:39 volumio volumio[841]: at _combinedTickCallback (internal/process/next_tick.js:141:11) May 04 13:02:39 volumio volumio[841]: at process._tickCallback (internal/process/next_tick.js:180:9) May 04 13:02:39 volumio volumio[841]: at Function.Module.runMain (module.js:695:11) May 04 13:02:39 volumio volumio[841]: at startup (bootstrap_node.js:188:16) May 04 13:02:39 volumio volumio[841]: at bootstrap_node.js:609:3 May 04 13:02:39 volumio volumio[841]: [SpotifyConnect] Error [ERR_SOCKET_CANNOT_SEND]: Unable to send data May 04 13:02:39 volumio volumio[841]: at Socket.onListenError (dgram.js:362:22) May 04 13:02:39 volumio volumio[841]: at Object.onceWrapper (events.js:315:30) May 04 13:02:39 volumio volumio[841]: at emitOne (events.js:121:20) May 04 13:02:39 volumio volumio[841]: at Socket.emit (events.js:211:7) May 04 13:02:39 volumio volumio[841]: at _handle.lookup (dgram.js:267:14) May 04 13:02:39 volumio volumio[841]: at _combinedTickCallback (internal/process/next_tick.js:141:11) May 04 13:02:39 volumio volumio[841]: at process._tickCallback (internal/process/next_tick.js:180:9) May 04 13:02:39 volumio volumio[841]: at Function.Module.runMain (module.js:695:11) May 04 13:02:39 volumio volumio[841]: at startup (bootstrap_node.js:188:16) May 04 13:02:39 volumio volumio[841]: at bootstrap_node.js:609:3 May 04 13:02:39 volumio volumio[841]: info: CoreStateMachine::resetVolumioState May 04 13:02:39 volumio volumio[841]: info: CoreStateMachine::getcurrentVolume May 04 13:02:39 volumio volumio[841]: info: CoreCommandRouter::volumioRetrievevolume May 04 13:02:39 volumio volumio[841]: info: CoreStateMachine::updateTrackBlock May 04 13:02:39 volumio volumio[841]: info: CorePlayQueue::getTrackBlock May 04 13:02:40 volumio volumio[841]: Discovery: ecc TypeError: argument 8 must be an integer (port) May 04 13:02:40 volumio volumio[841]: Discovery: StartAdv! false May 04 13:02:40 volumio volumio[841]: Discovery: Started advertising... Volumio - false May 04 13:02:40 volumio volumio[841]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 04 13:02:40 volumio volumio[841]: [SpotifyConnect] Vollibrespot Daemon service started! May 04 13:02:40 volumio volumio[841]: SpotifyConnect: 6106.048ms May 04 13:02:40 volumio volumio[841]: [SpotifyConnect] Vollibrespot Daemon service started! May 04 13:02:40 volumio volumio[841]: (node:841) Warning: No such label 'SpotifyConnect' for console.timeEnd() May 04 13:02:40 volumio volumio[841]: info: MPD running with PID564 ,establishing connection May 04 13:02:40 volumio sudo[955]: pam_unix(sudo:auth): conversation failed May 04 13:02:40 volumio sudo[955]: pam_unix(sudo:auth): auth could not identify password for [volumio] May 04 13:02:40 volumio volumio[841]: info: VolumeController:: Volume=87 Mute =false May 04 13:02:40 volumio volumio[841]: info: CoreStateMachine::pushState May 04 13:02:40 volumio volumio[841]: info: CoreStateMachine::getState May 04 13:02:40 volumio volumio[841]: info: CorePlayQueue::getTrack 0 May 04 13:02:40 volumio volumio[841]: info: CoreCommandRouter::volumioPushState May 04 13:02:40 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 13:02:40 volumio volumio[841]: info: interfaceApi::pushState May 04 13:02:40 volumio volumio[841]: info: May 04 13:02:40 volumio volumio[841]: ---------------------------- Client requests Volume 90 May 04 13:02:40 volumio volumio[841]: info: VolumeController::SetAlsaVolume90 May 04 13:02:40 volumio volumio[841]: info: Pushing Favourites {"service":"mpd","uri":"","favourite":false} May 04 13:02:40 volumio volumio[841]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 May 04 13:02:40 volumio volumio[841]: sudo: no tty present and no askpass program specified May 04 13:02:40 volumio volumio[841]: info: Reloading queue from file May 04 13:02:40 volumio volumio[841]: info: May 04 13:02:40 volumio volumio[841]: null May 04 13:02:40 volumio volumio[841]: info: VolumeController::Volume 90 May 04 13:02:40 volumio volumio[841]: info: CoreStateMachine::pushState May 04 13:02:40 volumio volumio[841]: info: CoreStateMachine::getState May 04 13:02:40 volumio volumio[841]: info: CorePlayQueue::getTrack 0 May 04 13:02:40 volumio volumio[841]: info: CoreCommandRouter::volumioPushState May 04 13:02:40 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 13:02:40 volumio volumio[841]: info: interfaceApi::pushState May 04 13:02:40 volumio volumio[841]: info: Setting volume on startup at 90 May 04 13:02:40 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 04 13:02:40 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 04 13:02:40 volumio volumio[841]: info: CoreStateMachine::setRepeat null single undefined May 04 13:02:40 volumio volumio[841]: info: CoreStateMachine::pushState May 04 13:02:40 volumio volumio[841]: info: CoreStateMachine::getState May 04 13:02:40 volumio volumio[841]: info: CorePlayQueue::getTrack 0 May 04 13:02:40 volumio volumio[841]: info: CoreCommandRouter::volumioPushState May 04 13:02:40 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 13:02:40 volumio volumio[841]: info: interfaceApi::pushState May 04 13:02:40 volumio volumio[841]: info: CoreStateMachine::setRandom null May 04 13:02:40 volumio volumio[841]: info: CoreStateMachine::pushState May 04 13:02:40 volumio volumio[841]: info: CoreStateMachine::getState May 04 13:02:40 volumio volumio[841]: info: CorePlayQueue::getTrack 0 May 04 13:02:40 volumio volumio[841]: info: CoreCommandRouter::volumioPushState May 04 13:02:40 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 13:02:40 volumio volumio[841]: info: interfaceApi::pushState May 04 13:02:40 volumio volumio[841]: info: Pushing Favourites {"service":"webradio","uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996","favourite":false} May 04 13:02:41 volumio volumio[841]: info: Pushing Favourites {"service":"webradio","uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996","favourite":false} May 04 13:02:41 volumio volumio[841]: info: Pushing Favourites {"service":"webradio","uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996","favourite":false} May 04 13:02:41 volumio sudo[960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 04 13:02:41 volumio sudo[960]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 13:02:41 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... May 04 13:02:41 volumio sudo[962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay May 04 13:02:41 volumio sudo[962]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 13:02:41 volumio systemd[1]: Started UPnP Renderer front-end to MPD. May 04 13:02:41 volumio sudo[960]: pam_unix(sudo:session): session closed for user root May 04 13:02:41 volumio systemd[1]: Starting ShairportSync AirTunes receiver... May 04 13:02:41 volumio systemd[1]: Started ShairportSync AirTunes receiver. May 04 13:02:41 volumio ifup[473]: dhcpcd[494]: timed out May 04 13:02:41 volumio dhcpcd[494]: timed out May 04 13:02:41 volumio dhcpcd[494]: exited May 04 13:02:41 volumio ifup[473]: dhcpcd[494]: exited May 04 13:02:41 volumio ifup[473]: Failed to bring up eth0. May 04 13:02:41 volumio sudo[962]: pam_unix(sudo:session): session closed for user root May 04 13:02:41 volumio shairport-sync[966]: Successful Startup May 04 13:02:41 volumio volumio[841]: info: Upmpdcli Daemon Started May 04 13:02:41 volumio systemd[1]: volspotconnect.service holdoff time over, scheduling restart. May 04 13:02:41 volumio systemd[1]: Stopping Volspotconnect2 Daemon... May 04 13:02:41 volumio systemd[1]: Starting Volspotconnect2 Daemon... May 04 13:02:41 volumio systemd[1]: Started Volspotconnect2 Daemon. May 04 13:02:41 volumio volumio[971]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 May 04 13:02:41 volumio volumio[971]: Reading Config from "/tmp/volspotify.toml" May 04 13:02:41 volumio volumio[971]: [Vollibrespot] : Using Alsa backend with device: plughw:0 May 04 13:02:41 volumio volumio[841]: info: [1746363761682] Shairport-Sync Started May 04 13:02:41 volumio volumio[841]: Error adding Membership: Error: addMembership EINVAL May 04 13:02:41 volumio volumio[841]: info: CoreCommandRouter::volumioGetState May 04 13:02:41 volumio volumio[841]: info: CoreStateMachine::getState May 04 13:02:41 volumio volumio[841]: info: CorePlayQueue::getTrack 0 May 04 13:02:42 volumio volumio[971]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } May 04 13:02:43 volumio volumio-remote-updater[537]: [2025-05-04 13:02:43] [connect] Successful connection May 04 13:02:43 volumio volumio-remote-updater[537]: [2025-05-04 13:02:43] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1746363763 101 May 04 13:02:44 volumio volumio[841]: info: Checking Spotify Web API May 04 13:02:44 volumio volumio[841]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 13:02:44 volumio volumio[841]: { Error: addMembership ENODEV May 04 13:02:44 volumio volumio[841]: at _errnoException (util.js:1022:11) May 04 13:02:44 volumio volumio[841]: at Socket.addMembership (dgram.js:615:11) May 04 13:02:44 volumio volumio[841]: at Timeout.addMembership [as _onTimeout] (/volumio/node_modules/node-ssdp/lib/index.js:261:16) May 04 13:02:44 volumio volumio[841]: at ontimeout (timers.js:482:11) May 04 13:02:44 volumio volumio[841]: at tryOnTimeout (timers.js:317:5) May 04 13:02:44 volumio volumio[841]: at Timer.listOnTimeout (timers.js:277:5) code: 'ENODEV', errno: 'ENODEV', syscall: 'addMembership' } May 04 13:02:44 volumio volumio[841]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 13:02:46 volumio sudo[1019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-04 13:01 May 04 13:02:46 volumio sudo[1019]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="574bc71e9a507184c95992f1952e3e291e5b75f9" VOLUMIO_FE_VERSION="62ac6c881ff35a4b2c2cb32bcb82fbb6ebb2b1ed" VOLUMIO_BE_VERSION="0f9096de91319d0886c3f67e6e895d0bfff2c195" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu May 31 20:45:27 NZST 2018" VOLUMIO_VERSION="-2.406" VOLUMIO_HARDWARE="orangepizero"