-- Logs begin at Fri 2024-10-25 10:36:34 UTC, end at Sat 2024-10-26 04:01:55 UTC. -- Oct 26 04:00:05 volumio01 volumio[7036]: Upnp client error: Error: read ECONNRESET Oct 26 04:00:22 volumio01 volumio[7036]: error: error Oct 26 04:00:22 volumio01 volumio[7036]: error: error Oct 26 04:00:23 volumio01 avahi-daemon[617]: Received packet from invalid interface. Oct 26 04:00:23 volumio01 avahi-daemon[617]: Received packet from invalid interface. Oct 26 04:00:23 volumio01 avahi-daemon[617]: Received packet from invalid interface. Oct 26 04:00:23 volumio01 avahi-daemon[617]: Received packet from invalid interface. Oct 26 04:00:23 volumio01 avahi-daemon[617]: Received packet from invalid interface. Oct 26 04:00:23 volumio01 avahi-daemon[617]: Received packet from invalid interface. Oct 26 04:00:48 volumio01 volumio[7036]: Upnp client error: Error: This socket has been ended by the other party Oct 26 04:00:48 volumio01 avahi-daemon[617]: Received packet from invalid interface. Oct 26 04:01:05 volumio01 volumio[7036]: error: error Oct 26 04:01:05 volumio01 volumio[7036]: error: error Oct 26 04:01:36 volumio01 avahi-daemon[617]: Received packet from invalid interface. Oct 26 04:01:36 volumio01 avahi-daemon[617]: Received packet from invalid interface. Oct 26 04:01:36 volumio01 avahi-daemon[617]: Received packet from invalid interface. Oct 26 04:01:36 volumio01 avahi-daemon[617]: Received packet from invalid interface. Oct 26 04:01:36 volumio01 avahi-daemon[617]: Received packet from invalid interface. Oct 26 04:01:36 volumio01 avahi-daemon[617]: Received packet from invalid interface. Oct 26 04:01:37 volumio01 avahi-daemon[617]: Received packet from invalid interface. Oct 26 04:01:40 volumio01 volumio[7036]: <--- Last few GCs ---> Oct 26 04:01:40 volumio01 volumio[7036]: [7036:0x2d61f20] 75883850 ms: Mark-sweep 747.2 (1000.4) -> 747.2 (1000.4) MB, 5490.0 / 7.0 ms allocation failure GC in old space requested Oct 26 04:01:40 volumio01 volumio[7036]: [7036:0x2d61f20] 75891687 ms: Mark-sweep 747.2 (1000.4) -> 747.2 (969.9) MB, 7835.7 / 6.6 ms last resort GC in old space requested Oct 26 04:01:40 volumio01 volumio[7036]: [7036:0x2d61f20] 75898780 ms: Mark-sweep 747.2 (969.9) -> 747.2 (941.9) MB, 7092.6 / 6.3 ms last resort GC in old space requested Oct 26 04:01:40 volumio01 volumio[7036]: <--- JS stacktrace ---> Oct 26 04:01:40 volumio01 volumio[7036]: ==== JS stack trace ========================================= Oct 26 04:01:40 volumio01 volumio[7036]: Security context: 0x2b596169 Oct 26 04:01:40 volumio01 volumio[7036]: 1: handshake [/volumio/node_modules/engine.io/lib/server.js:~264] [pc=0x35254a90](this=0x4accda81 ,transportName=0x428e3619 ,req=0x428e32bd ) Oct 26 04:01:40 volumio01 volumio[7036]: 2: /* anonymous */ [/volumio/node_modules/engine.io/lib/server.js:215] [bytecode=0x391c498d offset=141](this=0x2bd04101 ,err=0x46804101 ,success=0x468... Oct 26 04:01:40 volumio01 volumio[7036]: FATAL ERROR: CALL_AND_RETRY_LAST Allocation failed - JavaScript heap out of memory Oct 26 04:01:40 volumio01 volumio-remote-updater[10871]: [2024-10-26 04:01:40] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Oct 26 04:01:40 volumio01 volumio-remote-updater[10871]: [2024-10-26 04:01:40] [error] handle_read_frame error: websocketpp.transport:2 (Underlying Transport Error) Oct 26 04:01:40 volumio01 volumio-remote-updater[10871]: [2024-10-26 04:01:40] [disconnect] Disconnect close local:[1006,Underlying Transport Error] remote:[1006] Oct 26 04:01:40 volumio01 systemd[1]: volumio.service: main process exited, code=killed, status=6/ABRT Oct 26 04:01:40 volumio01 systemd[1]: Unit volumio.service entered failed state. Oct 26 04:01:40 volumio01 systemd[1]: Starting dynamicswap service... Oct 26 04:01:40 volumio01 systemd[1]: Started dynamicswap service. Oct 26 04:01:40 volumio01 avahi-daemon[617]: Received packet from invalid interface. Oct 26 04:01:40 volumio01 systemd[1]: volumio.service holdoff time over, scheduling restart. Oct 26 04:01:40 volumio01 systemd[1]: Starting dynamicswap service... Oct 26 04:01:40 volumio01 systemd[1]: Started dynamicswap service. Oct 26 04:01:40 volumio01 systemd[1]: Stopping Volumio Backend Module... Oct 26 04:01:40 volumio01 systemd[1]: Starting Volumio Backend Module... Oct 26 04:01:40 volumio01 systemd[1]: Started Volumio Backend Module. Oct 26 04:01:41 volumio01 avahi-daemon[617]: Received packet from invalid interface. Oct 26 04:01:42 volumio01 volumio[27227]: info: ------------------------------------------- Oct 26 04:01:42 volumio01 volumio[27227]: info: ----- Volumio2 ---- Oct 26 04:01:42 volumio01 volumio[27227]: info: ------------------------------------------- Oct 26 04:01:42 volumio01 volumio[27227]: info: ----- System startup ---- Oct 26 04:01:42 volumio01 volumio[27227]: info: ------------------------------------------- Oct 26 04:01:42 volumio01 volumio[27227]: info: MYVOLUMIO Environment detected Oct 26 04:01:43 volumio01 volumio[27227]: info: Plugin folders cleanup Oct 26 04:01:43 volumio01 volumio[27227]: info: Scanning into folder /volumio/app/plugins/ Oct 26 04:01:43 volumio01 volumio[27227]: info: Scanning category audio_interface Oct 26 04:01:43 volumio01 volumio[27227]: info: Scanning category miscellanea Oct 26 04:01:43 volumio01 volumio[27227]: info: Scanning category music_service Oct 26 04:01:43 volumio01 volumio[27227]: info: Scanning category plugins.json Oct 26 04:01:43 volumio01 volumio[27227]: info: Scanning category system_controller Oct 26 04:01:43 volumio01 volumio[27227]: info: Scanning category user_interface Oct 26 04:01:43 volumio01 volumio[27227]: info: Scanning into folder /data/plugins/ Oct 26 04:01:43 volumio01 volumio[27227]: info: Scanning category music_service Oct 26 04:01:43 volumio01 volumio[27227]: info: Plugin folders cleanup completed Oct 26 04:01:43 volumio01 volumio[27227]: info: ------------------------------------------- Oct 26 04:01:43 volumio01 volumio[27227]: info: ----- Core plugins startup ---- Oct 26 04:01:43 volumio01 volumio[27227]: info: ------------------------------------------- Oct 26 04:01:43 volumio01 volumio[27227]: info: Loading plugins from folder /volumio/app/plugins/ Oct 26 04:01:43 volumio01 volumio[27227]: info: Adding plugin upnp to MyMusic Plugins Oct 26 04:01:43 volumio01 volumio[27227]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 26 04:01:43 volumio01 volumio[27227]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 26 04:01:43 volumio01 volumio[27227]: info: Loading plugins from folder /data/plugins/ Oct 26 04:01:43 volumio01 volumio[27227]: info: Loading plugin "system"... Oct 26 04:01:43 volumio01 volumio[27227]: info: Loading plugin "appearance"... Oct 26 04:01:43 volumio01 volumio[27227]: info: Loading plugin "network"... Oct 26 04:01:44 volumio01 volumio[27227]: info: Loading plugin "services"... Oct 26 04:01:44 volumio01 volumio[27227]: info: Loading plugin "alsa_controller"... Oct 26 04:01:44 volumio01 volumio[27227]: info: Loading plugin "wizard"... Oct 26 04:01:44 volumio01 volumio[27227]: info: Loading plugin "volumio_command_line_client"... Oct 26 04:01:44 volumio01 volumio[27227]: info: Loading plugin "upnp"... Oct 26 04:01:44 volumio01 volumio[27227]: info: [1729915304544] Starting Upmpd Daemon Oct 26 04:01:44 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 26 04:01:44 volumio01 volumio[27227]: info: Loading plugin "my_music"... Oct 26 04:01:44 volumio01 volumio[27227]: info: Loading plugin "mpd"... Oct 26 04:01:44 volumio01 volumio[27227]: info: Loading plugin "upnp_browser"... Oct 26 04:01:45 volumio01 volumio[27227]: info: Loading plugin "networkfs"... Oct 26 04:01:45 volumio01 volumio[27227]: info: Cannot mount NAS music at system boot, trial number 1 ,retrying in 5 seconds Oct 26 04:01:45 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 26 04:01:45 volumio01 volumio[27227]: info: Loading plugin "alarm-clock"... Oct 26 04:01:45 volumio01 volumio[27227]: info: Loading plugin "airplay_emulation"... Oct 26 04:01:45 volumio01 volumio[27227]: info: Starting Shairport Sync Oct 26 04:01:45 volumio01 volumio[27227]: info: Loading plugin "last_100"... Oct 26 04:01:45 volumio01 volumio[27227]: info: Loading plugin "webradio"... Oct 26 04:01:45 volumio01 volumio-remote-updater[10871]: [2024-10-26 04:01:45] [connect] Successful connection Oct 26 04:01:45 volumio01 volumio[27227]: info: Loading plugin "i2s_dacs"... Oct 26 04:01:45 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 04:01:45 volumio01 volumio[27227]: info: Loading plugin "volumiodiscovery"... Oct 26 04:01:45 volumio01 volumio[27227]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 26 04:01:45 volumio01 node[27227]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 26 04:01:45 volumio01 node[27227]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 26 04:01:45 volumio01 node[27227]: *** WARNING *** For more information see Oct 26 04:01:45 volumio01 node[27227]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 26 04:01:45 volumio01 node[27227]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 26 04:01:45 volumio01 node[27227]: *** WARNING *** For more information see Oct 26 04:01:45 volumio01 volumio[27227]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 26 04:01:45 volumio01 volumio[27227]: *** WARNING *** For more information see Oct 26 04:01:45 volumio01 volumio[27227]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 26 04:01:45 volumio01 volumio[27227]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 26 04:01:45 volumio01 volumio[27227]: *** WARNING *** For more information see Oct 26 04:01:45 volumio01 volumio[27227]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 26 04:01:45 volumio01 volumio[27227]: Discovery: StartAdv! undefined Oct 26 04:01:45 volumio01 volumio[27227]: Discovery: Started advertising... Volumio01 - undefined Oct 26 04:01:45 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 26 04:01:45 volumio01 volumio[27227]: info: Loading plugin "outputs"... Oct 26 04:01:45 volumio01 volumio[27227]: info: Loading plugin "albumart"... Oct 26 04:01:45 volumio01 volumio[27227]: info: Plugin example_plugin is not enabled Oct 26 04:01:45 volumio01 volumio[27227]: info: Loading plugin "inputs"... Oct 26 04:01:45 volumio01 volumio[27227]: info: Loading plugin "updater_comm"... Oct 26 04:01:45 volumio01 volumio[27227]: info: Plugin mpdemulation is not enabled Oct 26 04:01:45 volumio01 volumio[27227]: info: Loading plugin "rest_api"... Oct 26 04:01:45 volumio01 volumio[27227]: info: Loading plugin "websocket"... Oct 26 04:01:45 volumio01 volumio[27227]: info: Loading plugin "RoonBridge"... Oct 26 04:01:46 volumio01 volumio[27227]: Forking 3 albumart workers Oct 26 04:01:46 volumio01 volumio[27227]: info: Applying required configuration parameters for plugin RoonBridge Oct 26 04:01:46 volumio01 volumio[27227]: info: Loading plugin "spop"... Oct 26 04:01:46 volumio01 volumio[27227]: Starting albumart workers Oct 26 04:01:46 volumio01 volumio[27227]: Starting albumart workers Oct 26 04:01:46 volumio01 volumio[27227]: Starting albumart workers Oct 26 04:01:47 volumio01 volumio[27227]: info: Loading plugin "volspotconnect2"... Oct 26 04:01:47 volumio01 volumio[27227]: info: ___________ START PLUGINS ___________ Oct 26 04:01:47 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 04:01:47 volumio01 volumio[27227]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 26 04:01:47 volumio01 volumio[27227]: info: [1729915307831] CoreMusicLibrary::Adding element Media Servers Oct 26 04:01:47 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 04:01:47 volumio01 volumio[27227]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 26 04:01:47 volumio01 volumio[27227]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 26 04:01:47 volumio01 volumio[27227]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 26 04:01:47 volumio01 volumio[27227]: info: [1729915307953] CoreMusicLibrary::Adding element Last_100 Oct 26 04:01:47 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 04:01:47 volumio01 volumio[27227]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 26 04:01:47 volumio01 volumio[27227]: info: [1729915307956] CoreMusicLibrary::Adding element Webradio Oct 26 04:01:47 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 04:01:47 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 04:01:47 volumio01 sudo[27307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Oct 26 04:01:48 volumio01 sudo[27307]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 04:01:48 volumio01 systemd[1]: Started RoonBridge. Oct 26 04:01:48 volumio01 sudo[27307]: pam_unix(sudo:session): session closed for user root Oct 26 04:01:48 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 26 04:01:48 volumio01 volumio[27227]: [SpotifyConnect] Creating VLS config file Oct 26 04:01:48 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 04:01:48 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 04:01:48 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 04:01:48 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 04:01:48 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 04:01:48 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 04:01:48 volumio01 volumio[27227]: [SpotifyConnect] Starting metadata listener Oct 26 04:01:48 volumio01 sudo[27313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Oct 26 04:01:48 volumio01 sudo[27313]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 04:01:48 volumio01 sudo[27313]: pam_unix(sudo:session): session closed for user root Oct 26 04:01:48 volumio01 volumio[27227]: [SpotifyConnect] Creating VLS config file Oct 26 04:01:48 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 04:01:48 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 04:01:48 volumio01 volumio[27227]: [SpotifyConnect] Starting metadata listener Oct 26 04:01:48 volumio01 sudo[27325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Oct 26 04:01:48 volumio01 sudo[27325]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 04:01:48 volumio01 volumio[27227]: info: ------------------------------------------- Oct 26 04:01:48 volumio01 volumio[27227]: info: ----- MyVolumio plugins startup ---- Oct 26 04:01:48 volumio01 volumio[27227]: info: ------------------------------------------- Oct 26 04:01:48 volumio01 volumio[27227]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 26 04:01:48 volumio01 systemd[1]: Started Volspotconnect2 Daemon. Oct 26 04:01:48 volumio01 sudo[27325]: pam_unix(sudo:session): session closed for user root Oct 26 04:01:48 volumio01 volumio[27227]: info: Loading i18n strings for locale ja Oct 26 04:01:48 volumio01 sudo[27331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Oct 26 04:01:48 volumio01 volumio[27227]: Updating browse sources language Oct 26 04:01:48 volumio01 volumio[27227]: Cannot find translation for sourceMedia Servers Oct 26 04:01:48 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 04:01:48 volumio01 sudo[27331]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 04:01:48 volumio01 systemd[1]: Started Volspotconnect2 Daemon. Oct 26 04:01:48 volumio01 sudo[27331]: pam_unix(sudo:session): session closed for user root Oct 26 04:01:48 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 04:01:48 volumio01 volumio[27227]: info: CoreCommandRouter::initPlayerControls Oct 26 04:01:48 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 04:01:48 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Oct 26 04:01:48 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 04:01:48 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 04:01:48 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 04:01:48 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 04:01:48 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 04:01:48 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 04:01:48 volumio01 volumio[27227]: info: BOOT COMPLETED Oct 26 04:01:48 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 04:01:48 volumio01 kernel: sabre9018q2c-i2c 1-0048: Master Mode Oct 26 04:01:48 volumio01 avahi-daemon[617]: Received packet from invalid interface. Oct 26 04:01:49 volumio01 avahi-daemon[617]: Received packet from invalid interface. Oct 26 04:01:52 volumio01 avahi-daemon[617]: Received packet from invalid interface. Oct 26 04:01:53 volumio01 volumio[27227]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Oct 26 04:01:53 volumio01 volumio[27227]: info: CoreCommandRouter::Close All Modals sent Oct 26 04:01:53 volumio01 volumio[27227]: info: CoreCommandRouter::Close All Modals sent Oct 26 04:01:53 volumio01 volumio[27227]: Express server listening on port 3000 Oct 26 04:01:53 volumio01 volumio[27227]: Volumio Calling Home Oct 26 04:01:53 volumio01 volumio[27227]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Oct 26 04:01:53 volumio01 volumio[27227]: at Object._errnoException (util.js:1022:11) Oct 26 04:01:53 volumio01 volumio[27227]: at _exceptionWithHostPort (util.js:1044:20) Oct 26 04:01:53 volumio01 volumio[27227]: at _handle.lookup (dgram.js:266:18) Oct 26 04:01:53 volumio01 volumio[27227]: at _combinedTickCallback (internal/process/next_tick.js:141:11) Oct 26 04:01:53 volumio01 volumio[27227]: at process._tickCallback (internal/process/next_tick.js:180:9) Oct 26 04:01:53 volumio01 volumio[27227]: at Function.Module.runMain (module.js:695:11) Oct 26 04:01:53 volumio01 volumio[27227]: at startup (bootstrap_node.js:188:16) Oct 26 04:01:53 volumio01 volumio[27227]: at bootstrap_node.js:609:3 Oct 26 04:01:53 volumio01 volumio[27227]: [SpotifyConnect] Error [ERR_SOCKET_CANNOT_SEND]: Unable to send data Oct 26 04:01:53 volumio01 volumio[27227]: at Socket.onListenError (dgram.js:362:22) Oct 26 04:01:53 volumio01 volumio[27227]: at Object.onceWrapper (events.js:315:30) Oct 26 04:01:53 volumio01 volumio[27227]: at emitOne (events.js:121:20) Oct 26 04:01:53 volumio01 volumio[27227]: at Socket.emit (events.js:211:7) Oct 26 04:01:53 volumio01 volumio[27227]: at _handle.lookup (dgram.js:267:14) Oct 26 04:01:53 volumio01 volumio[27227]: at _combinedTickCallback (internal/process/next_tick.js:141:11) Oct 26 04:01:53 volumio01 volumio[27227]: at process._tickCallback (internal/process/next_tick.js:180:9) Oct 26 04:01:53 volumio01 volumio[27227]: at Function.Module.runMain (module.js:695:11) Oct 26 04:01:53 volumio01 volumio[27227]: at startup (bootstrap_node.js:188:16) Oct 26 04:01:53 volumio01 volumio[27227]: at bootstrap_node.js:609:3 Oct 26 04:01:53 volumio01 volumio[27227]: info: CoreStateMachine::resetVolumioState Oct 26 04:01:53 volumio01 volumio[27227]: info: CoreStateMachine::getcurrentVolume Oct 26 04:01:53 volumio01 volumio[27227]: info: CoreCommandRouter::volumioRetrievevolume Oct 26 04:01:53 volumio01 volumio[27227]: info: Cannot mount NAS music at system boot, trial number 2 ,retrying in 5 seconds Oct 26 04:01:53 volumio01 volumio[27227]: info: Setting Device type: Raspberry PI Oct 26 04:01:53 volumio01 volumio[27227]: [SpotifyConnect] Vollibrespot Daemon service started! Oct 26 04:01:53 volumio01 volumio[27227]: SpotifyConnect: 5838.379ms Oct 26 04:01:53 volumio01 volumio[27227]: [SpotifyConnect] Vollibrespot Daemon service started! Oct 26 04:01:53 volumio01 volumio[27227]: (node:27227) Warning: No such label 'SpotifyConnect' for console.timeEnd() Oct 26 04:01:53 volumio01 volumio[27227]: info: MPD running with PID623 ,establishing connection Oct 26 04:01:54 volumio01 volumio[27227]: info: VolumeController:: Volume=100 Mute =false Oct 26 04:01:54 volumio01 volumio[27227]: info: CoreStateMachine::pushState Oct 26 04:01:54 volumio01 volumio[27227]: info: CoreStateMachine::getState Oct 26 04:01:54 volumio01 volumio[27227]: info: CorePlayQueue::getTrack 0 Oct 26 04:01:54 volumio01 volumio[27227]: info: CoreCommandRouter::volumioPushState Oct 26 04:01:54 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 04:01:54 volumio01 volumio[27227]: info: CoreStateMachine::updateTrackBlock Oct 26 04:01:54 volumio01 volumio[27227]: info: CorePlayQueue::getTrackBlock Oct 26 04:01:54 volumio01 volumio[27227]: info: CoreCommandRouter::volumioRetrievevolume Oct 26 04:01:54 volumio01 volumio[27227]: info: Oct 26 04:01:54 volumio01 volumio[27227]: ---------------------------- Client requests Volume 100 Oct 26 04:01:54 volumio01 volumio[27227]: info: VolumeController::SetAlsaVolume100 Oct 26 04:01:54 volumio01 volumio[27227]: info: CoreStateMachine::pushState Oct 26 04:01:54 volumio01 volumio[27227]: info: CoreStateMachine::getState Oct 26 04:01:54 volumio01 volumio[27227]: info: CorePlayQueue::getTrack 0 Oct 26 04:01:54 volumio01 volumio[27227]: info: CoreCommandRouter::volumioPushState Oct 26 04:01:54 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 04:01:54 volumio01 volumio[27227]: info: Reloading queue from file Oct 26 04:01:54 volumio01 volumio[27227]: info: Oct 26 04:01:54 volumio01 volumio[27227]: info: Oct 26 04:01:54 volumio01 avahi-daemon[617]: Received packet from invalid interface. Oct 26 04:01:54 volumio01 volumio[27227]: info: Setting volume on startup at 100 Oct 26 04:01:54 volumio01 volumio[27227]: info: CoreStateMachine::setRepeat false single undefined Oct 26 04:01:54 volumio01 volumio[27227]: info: CoreStateMachine::pushState Oct 26 04:01:54 volumio01 volumio[27227]: info: CoreStateMachine::getState Oct 26 04:01:54 volumio01 volumio[27227]: info: CorePlayQueue::getTrack 0 Oct 26 04:01:54 volumio01 volumio[27227]: info: CoreCommandRouter::volumioPushState Oct 26 04:01:54 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 04:01:54 volumio01 volumio[27227]: info: CoreStateMachine::setRandom null Oct 26 04:01:54 volumio01 volumio[27227]: info: CoreStateMachine::pushState Oct 26 04:01:54 volumio01 volumio[27227]: info: CoreStateMachine::getState Oct 26 04:01:54 volumio01 volumio[27227]: info: CorePlayQueue::getTrack 0 Oct 26 04:01:54 volumio01 volumio[27227]: info: CoreCommandRouter::volumioPushState Oct 26 04:01:54 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 04:01:54 volumio01 volumio[27227]: info: Starting Shairport Sync Oct 26 04:01:54 volumio01 volumio[27227]: info: Starting Shairport Sync Oct 26 04:01:54 volumio01 sudo[27366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 26 04:01:54 volumio01 sudo[27366]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 04:01:54 volumio01 sudo[27369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 26 04:01:54 volumio01 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 26 04:01:54 volumio01 volumio[27227]: info: VolumeController:: Volume=100 Mute =false Oct 26 04:01:54 volumio01 volumio[27227]: info: CoreStateMachine::pushState Oct 26 04:01:54 volumio01 avahi-daemon[617]: Received packet from invalid interface. Oct 26 04:01:54 volumio01 volumio[27227]: info: CoreStateMachine::getState Oct 26 04:01:54 volumio01 volumio[27227]: info: CorePlayQueue::getTrack 0 Oct 26 04:01:54 volumio01 volumio[27227]: info: CoreCommandRouter::volumioPushState Oct 26 04:01:54 volumio01 volumio[27227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 04:01:54 volumio01 systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Oct 26 04:01:54 volumio01 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 26 04:01:54 volumio01 sudo[27366]: pam_unix(sudo:session): session closed for user root Oct 26 04:01:54 volumio01 sudo[27369]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 04:01:54 volumio01 avahi-daemon[617]: Received packet from invalid interface. Oct 26 04:01:54 volumio01 avahi-daemon[617]: Received packet from invalid interface. Oct 26 04:01:54 volumio01 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 26 04:01:54 volumio01 systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Oct 26 04:01:54 volumio01 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 26 04:01:54 volumio01 sudo[27369]: pam_unix(sudo:session): session closed for user root Oct 26 04:01:54 volumio01 volumio[27227]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 04:01:54 volumio01 volumio[27227]: { Error: ENOSPC: no space left on device, write errno: -28, code: 'ENOSPC', syscall: 'write' } Oct 26 04:01:54 volumio01 volumio[27227]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 04:01:55 volumio01 sudo[27413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-26 04:00 Oct 26 04:01:55 volumio01 sudo[27413]: 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="7441f93fb55fc13e1243278874bfebef55eaac89" VOLUMIO_FE_VERSION="" VOLUMIO_BE_VERSION="60cbd8a1e03b2e464c11c01f54954168a34e9b45" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Feb 15 03:28:59 CET 2020" VOLUMIO_VERSION="2.703" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="c6482eede154d29f2cc010e5a630bcb4"