-- Logs begin at Tue 2018-12-04 20:04:59 UTC, end at Mon 2024-04-15 12:35:24 UTC. -- Apr 15 12:34:54 volumio systemd[1]: Time has been changed Apr 15 12:34:55 volumio wireless.js[654]: trying... Apr 15 12:34:55 volumio sudo[1025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 15 12:34:55 volumio sudo[1025]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:34:55 volumio sudo[1025]: pam_unix(sudo:session): session closed for user root Apr 15 12:34:56 volumio wireless.js[654]: trying... Apr 15 12:34:56 volumio sudo[1034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 15 12:34:56 volumio sudo[1034]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:34:56 volumio sudo[1034]: pam_unix(sudo:session): session closed for user root Apr 15 12:34:57 volumio wireless.js[654]: trying... Apr 15 12:34:57 volumio sudo[1043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 15 12:34:57 volumio sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:34:57 volumio sudo[1043]: pam_unix(sudo:session): session closed for user root Apr 15 12:34:58 volumio wireless.js[654]: trying... Apr 15 12:34:58 volumio sudo[1052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 15 12:34:58 volumio sudo[1052]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:34:58 volumio sudo[1052]: pam_unix(sudo:session): session closed for user root Apr 15 12:34:59 volumio wireless.js[654]: trying... Apr 15 12:34:59 volumio sudo[1061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 15 12:34:59 volumio sudo[1061]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:34:59 volumio sudo[1061]: pam_unix(sudo:session): session closed for user root Apr 15 12:35:00 volumio wireless.js[654]: trying... Apr 15 12:35:00 volumio sudo[1070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 15 12:35:00 volumio sudo[1070]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:35:00 volumio sudo[1070]: pam_unix(sudo:session): session closed for user root Apr 15 12:35:01 volumio wireless.js[654]: trying... Apr 15 12:35:01 volumio sudo[1079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 15 12:35:01 volumio sudo[1079]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:35:01 volumio sudo[1079]: pam_unix(sudo:session): session closed for user root Apr 15 12:35:01 volumio volumio-remote-updater[538]: [2024-04-15 12:35:01] [info] asio async_connect error: system:111 (Connection refused) Apr 15 12:35:01 volumio volumio-remote-updater[538]: [2024-04-15 12:35:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 15 12:35:01 volumio volumio-remote-updater[538]: [2024-04-15 12:35:01] [error] handle_connect error: Underlying Transport Error Apr 15 12:35:02 volumio wpa_supplicant[884]: wlan0: Failed to initiate sched scan Apr 15 12:35:02 volumio wireless.js[654]: trying... Apr 15 12:35:02 volumio sudo[1088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 15 12:35:02 volumio sudo[1088]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:35:02 volumio sudo[1088]: pam_unix(sudo:session): session closed for user root Apr 15 12:35:03 volumio wireless.js[654]: trying... Apr 15 12:35:03 volumio sudo[1097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 15 12:35:03 volumio sudo[1097]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:35:03 volumio sudo[1097]: pam_unix(sudo:session): session closed for user root Apr 15 12:35:04 volumio wireless.js[654]: trying... Apr 15 12:35:04 volumio sudo[1106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 15 12:35:04 volumio sudo[1106]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:35:04 volumio sudo[1106]: pam_unix(sudo:session): session closed for user root Apr 15 12:35:05 volumio wireless.js[654]: Overtime, starting plan B Apr 15 12:35:05 volumio systemd[1]: Started Wireless Services. Apr 15 12:35:05 volumio systemd[1]: Starting Volumio Backend Module... Apr 15 12:35:05 volumio systemd[1]: Started Volumio Backend Module. Apr 15 12:35:05 volumio systemd[1]: Starting Volumio Streaming Daemon... Apr 15 12:35:05 volumio systemd[1]: Started Volumio Streaming Daemon. Apr 15 12:35:05 volumio systemd[1]: Starting Volumio Kiosk... Apr 15 12:35:05 volumio systemd[1]: Started Volumio Kiosk. Apr 15 12:35:05 volumio systemd[1]: Starting Multi-User System. Apr 15 12:35:05 volumio systemd[1]: Reached target Multi-User System. Apr 15 12:35:05 volumio systemd[1]: Starting Graphical Interface. Apr 15 12:35:05 volumio systemd[1]: Reached target Graphical Interface. Apr 15 12:35:05 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 15 12:35:05 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 15 12:35:05 volumio systemd[1]: Startup finished in 8.617s (kernel) + 32.328s (userspace) = 40.946s. Apr 15 12:35:05 volumio volumio-streaming-daemon[1116]: ############################ Apr 15 12:35:05 volumio volumio-streaming-daemon[1116]: # Volumio Streaming Daemon # Apr 15 12:35:05 volumio volumio-streaming-daemon[1116]: # Running on port 7777 # Apr 15 12:35:05 volumio volumio-streaming-daemon[1116]: ############################ Apr 15 12:35:05 volumio startx[1117]: X.Org X Server 1.18.4 Apr 15 12:35:05 volumio startx[1117]: Release Date: 2016-07-19 Apr 15 12:35:05 volumio startx[1117]: X Protocol Version 11, Revision 0 Apr 15 12:35:05 volumio startx[1117]: Build Operating System: Linux 4.4.26-v7+ armv7l Raspbian Apr 15 12:35:05 volumio startx[1117]: Current Operating System: Linux volumio 4.14.71-v7+ #1145 SMP Fri Sep 21 15:38:35 BST 2018 armv7l Apr 15 12:35:05 volumio startx[1117]: Kernel command line: 8250.nr_uarts=0 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash quiet plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 console=ttyS0,115200 kgdboc=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh elevator=noop rootwait bootdelay=5 logo.nologo vt.global_cursor_default=0 loglevel=0 Apr 15 12:35:05 volumio startx[1117]: Build Date: 11 November 2016 11:59:59AM Apr 15 12:35:05 volumio startx[1117]: xorg-server 2:1.18.4-2+rpi1 (https://www.debian.org/support) Apr 15 12:35:05 volumio startx[1117]: Current version of pixman: 0.32.6 Apr 15 12:35:05 volumio startx[1117]: Before reporting problems, check http://wiki.x.org Apr 15 12:35:05 volumio startx[1117]: to make sure that you have the latest version. Apr 15 12:35:05 volumio startx[1117]: Markers: (--) probed, (**) from config file, (==) default setting, Apr 15 12:35:05 volumio startx[1117]: (++) from command line, (!!) notice, (II) informational, Apr 15 12:35:05 volumio startx[1117]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Apr 15 12:35:05 volumio startx[1117]: (==) Log file: "/home/volumio/.local/share/xorg/Xorg.0.log", Time: Mon Apr 15 12:35:05 2024 Apr 15 12:35:05 volumio startx[1117]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Apr 15 12:35:05 volumio startx[1117]: (EE) Apr 15 12:35:05 volumio startx[1117]: Fatal server error: Apr 15 12:35:05 volumio startx[1117]: (EE) parse_vt_settings: Cannot open /dev/tty0 (Permission denied) Apr 15 12:35:05 volumio startx[1117]: (EE) Apr 15 12:35:05 volumio startx[1117]: (EE) Apr 15 12:35:05 volumio startx[1117]: Please consult the The X.Org Foundation support Apr 15 12:35:05 volumio startx[1117]: at http://wiki.x.org Apr 15 12:35:05 volumio startx[1117]: for help. Apr 15 12:35:05 volumio startx[1117]: (EE) Please also check the log file at "/home/volumio/.local/share/xorg/Xorg.0.log" for additional information. Apr 15 12:35:05 volumio startx[1117]: (EE) Apr 15 12:35:05 volumio startx[1117]: (EE) Server terminated with error (1). Closing log file. Apr 15 12:35:07 volumio volumio[1115]: info: ------------------------------------------- Apr 15 12:35:07 volumio volumio[1115]: info: ----- Volumio2 ---- Apr 15 12:35:07 volumio volumio[1115]: info: ------------------------------------------- Apr 15 12:35:07 volumio volumio[1115]: info: ----- System startup ---- Apr 15 12:35:07 volumio volumio[1115]: info: ------------------------------------------- Apr 15 12:35:07 volumio volumio[1115]: info: Plugin folders cleanup Apr 15 12:35:07 volumio volumio[1115]: info: Scanning into folder /volumio/app/plugins/ Apr 15 12:35:07 volumio volumio[1115]: info: Scanning category audio_interface Apr 15 12:35:07 volumio volumio[1115]: info: Scanning category miscellanea Apr 15 12:35:07 volumio volumio[1115]: info: Scanning category music_service Apr 15 12:35:07 volumio volumio[1115]: info: Scanning category plugins.json Apr 15 12:35:07 volumio volumio[1115]: info: Scanning category system_controller Apr 15 12:35:07 volumio volumio[1115]: info: Scanning category user_interface Apr 15 12:35:07 volumio volumio[1115]: info: Scanning into folder /data/plugins/ Apr 15 12:35:07 volumio volumio[1115]: info: Scanning category audio_interface Apr 15 12:35:07 volumio volumio[1115]: info: Scanning category miscellanea Apr 15 12:35:07 volumio volumio[1115]: info: Scanning category music_service Apr 15 12:35:07 volumio volumio[1115]: info: Plugin folders cleanup completed Apr 15 12:35:07 volumio volumio[1115]: info: ------------------------------------------- Apr 15 12:35:07 volumio volumio[1115]: info: ----- Core plugins startup ---- Apr 15 12:35:07 volumio volumio[1115]: info: ------------------------------------------- Apr 15 12:35:07 volumio volumio[1115]: info: Loading plugins from folder /volumio/app/plugins/ Apr 15 12:35:07 volumio volumio[1115]: info: Adding plugin upnp to MyMusic Plugins Apr 15 12:35:07 volumio volumio[1115]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 15 12:35:07 volumio volumio[1115]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 15 12:35:07 volumio volumio[1115]: info: Loading plugins from folder /data/plugins/ Apr 15 12:35:07 volumio volumio[1115]: info: Loading plugin "system"... Apr 15 12:35:08 volumio volumio[1115]: info: Loading plugin "appearance"... Apr 15 12:35:09 volumio volumio[1115]: info: Loading plugin "network"... Apr 15 12:35:09 volumio volumio[1115]: info: Loading plugin "touch_display"... Apr 15 12:35:10 volumio wpa_supplicant[884]: wlan0: Failed to initiate sched scan Apr 15 12:35:10 volumio volumio[1115]: info: Applying required configuration parameters for plugin touch_display Apr 15 12:35:10 volumio volumio[1115]: info: Loading plugin "services"... Apr 15 12:35:10 volumio volumio[1115]: info: Loading plugin "alsa_controller"... Apr 15 12:35:10 volumio volumio[1115]: info: Loading plugin "wizard"... Apr 15 12:35:10 volumio volumio[1115]: info: Loading plugin "volumio_command_line_client"... Apr 15 12:35:10 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 12:35:10 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 12:35:10 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 12:35:10 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 12:35:10 volumio volumio[1115]: info: Loading plugin "upnp"... Apr 15 12:35:10 volumio volumio[1115]: info: [1713184510726] Starting Upmpd Daemon Apr 15 12:35:10 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 15 12:35:10 volumio volumio[1115]: info: Loading plugin "my_music"... Apr 15 12:35:10 volumio volumio[1115]: info: Loading plugin "mpd"... Apr 15 12:35:11 volumio volumio[1115]: info: Loading plugin "upnp_browser"... Apr 15 12:35:11 volumio volumio-remote-updater[538]: [2024-04-15 12:35:11] [connect] Successful connection Apr 15 12:35:12 volumio volumio[1115]: info: Loading plugin "networkfs"... Apr 15 12:35:12 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 15 12:35:12 volumio volumio[1115]: info: Loading plugin "alarm-clock"... Apr 15 12:35:12 volumio volumio[1115]: info: Loading plugin "airplay_emulation"... Apr 15 12:35:12 volumio volumio[1115]: info: Starting Shairport Sync Apr 15 12:35:12 volumio volumio[1115]: info: Loading plugin "last_100"... Apr 15 12:35:12 volumio volumio[1115]: info: Loading plugin "webradio"... Apr 15 12:35:12 volumio volumio[1115]: info: Loading plugin "i2s_dacs"... Apr 15 12:35:12 volumio volumio[1115]: info: I2S DAC not set, start Auto-detection Apr 15 12:35:12 volumio volumio[1115]: info: Loading plugin "volumiodiscovery"... Apr 15 12:35:12 volumio volumio[1115]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 15 12:35:12 volumio node[1115]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 15 12:35:12 volumio node[1115]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 15 12:35:12 volumio node[1115]: *** WARNING *** For more information see Apr 15 12:35:12 volumio node[1115]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 15 12:35:12 volumio node[1115]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 15 12:35:12 volumio node[1115]: *** WARNING *** For more information see Apr 15 12:35:12 volumio volumio[1115]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 15 12:35:12 volumio volumio[1115]: *** WARNING *** For more information see Apr 15 12:35:12 volumio volumio[1115]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 15 12:35:12 volumio volumio[1115]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 15 12:35:12 volumio volumio[1115]: *** WARNING *** For more information see Apr 15 12:35:12 volumio volumio[1115]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 15 12:35:12 volumio volumio[1115]: Discovery: StartAdv! undefined Apr 15 12:35:12 volumio volumio[1115]: Discovery: Started advertising... Volumio - undefined Apr 15 12:35:12 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 15 12:35:12 volumio volumio[1115]: info: Loading plugin "volsimpleequal"... Apr 15 12:35:12 volumio volumio[1115]: info: Applying required configuration parameters for plugin volsimpleequal Apr 15 12:35:12 volumio volumio[1115]: info: Plugin youtube is not enabled Apr 15 12:35:12 volumio volumio[1115]: info: Loading plugin "albumart"... Apr 15 12:35:12 volumio volumio[1115]: info: Plugin example_plugin is not enabled Apr 15 12:35:12 volumio volumio[1115]: info: Loading plugin "updater_comm"... Apr 15 12:35:13 volumio volumio[1115]: info: Plugin mpdemulation is not enabled Apr 15 12:35:13 volumio volumio[1115]: info: Loading plugin "rest_api"... Apr 15 12:35:13 volumio volumio[1115]: info: Loading plugin "websocket"... Apr 15 12:35:13 volumio volumio[1115]: info: Plugin onedrive_music_library is not enabled Apr 15 12:35:13 volumio volumio[1115]: info: Plugin personal_radio is not enabled Apr 15 12:35:13 volumio volumio[1115]: info: Plugin radio_paradise is not enabled Apr 15 12:35:13 volumio volumio[1115]: info: Loading plugin "spop"... Apr 15 12:35:13 volumio volumio[1115]: Forking 3 albumart workers Apr 15 12:35:14 volumio volumio[1115]: Starting albumart workers Apr 15 12:35:14 volumio volumio[1115]: Starting albumart workers Apr 15 12:35:14 volumio volumio[1115]: Starting albumart workers Apr 15 12:35:15 volumio volumio[1115]: info: Loading plugin "volspotconnect2"... Apr 15 12:35:15 volumio volumio[1115]: info: ___________ START PLUGINS ___________ Apr 15 12:35:15 volumio volumio[1115]: info: Loading i18n strings for locale it Apr 15 12:35:15 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 12:35:15 volumio volumio[1115]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 15 12:35:15 volumio volumio[1115]: info: [1713184515813] CoreMusicLibrary::Adding element Media Servers Apr 15 12:35:15 volumio sudo[1193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Apr 15 12:35:15 volumio sudo[1193]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:35:15 volumio systemd[1]: Reloading. Apr 15 12:35:15 volumio volumio[1115]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 15 12:35:15 volumio volumio[1115]: info: [1713184515869] CoreMusicLibrary::Adding element Last_100 Apr 15 12:35:15 volumio volumio[1115]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 15 12:35:15 volumio volumio[1115]: info: [1713184515872] CoreMusicLibrary::Adding element Webradio Apr 15 12:35:15 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 15 12:35:15 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Apr 15 12:35:15 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Apr 15 12:35:15 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Apr 15 12:35:15 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Apr 15 12:35:15 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 12:35:15 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 12:35:15 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 12:35:15 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 12:35:15 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 12:35:15 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 12:35:15 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 12:35:15 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 12:35:15 volumio sudo[1216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd_aloop index=7 pcm_substreams=2 Apr 15 12:35:15 volumio sudo[1216]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:35:16 volumio sudo[1216]: pam_unix(sudo:session): session closed for user root Apr 15 12:35:16 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 15 12:35:16 volumio sudo[1193]: pam_unix(sudo:session): session closed for user root Apr 15 12:35:16 volumio volumio[1115]: [SpotifyConnect] Creating VLS config file Apr 15 12:35:16 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 12:35:16 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 12:35:16 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 12:35:16 volumio volumio[1115]: [SpotifyConnect] SpotifyConnect Daemon config issues! Apr 15 12:35:16 volumio volumio[1115]: [SpotifyConnect] volspotify config error: [ '#initial-volume = undefined', [length]: 1 ] Apr 15 12:35:16 volumio sudo[1232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Apr 15 12:35:16 volumio volumio[1115]: [SpotifyConnect] Starting metadata listener Apr 15 12:35:16 volumio sudo[1232]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:35:16 volumio sudo[1232]: pam_unix(sudo:session): session closed for user root Apr 15 12:35:16 volumio sudo[1242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Apr 15 12:35:16 volumio sudo[1242]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:35:16 volumio volumio[1115]: info: ------------------------------------------- Apr 15 12:35:16 volumio volumio[1115]: info: ----- MyVolumio plugins startup ---- Apr 15 12:35:16 volumio volumio[1115]: info: ------------------------------------------- Apr 15 12:35:16 volumio volumio[1115]: info: Loading plugins from folder /myvolumio/plugins Apr 15 12:35:16 volumio volumio[1115]: info: Loading plugins from folder /data/myvolumio/plugins Apr 15 12:35:16 volumio volumio[1115]: info: Loading plugin "my_volumio"... Apr 15 12:35:16 volumio sudo[1250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Apr 15 12:35:16 volumio systemd[1]: Starting Volspotconnect2 Daemon... Apr 15 12:35:16 volumio sudo[1250]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:35:16 volumio systemd[1]: Started Volspotconnect2 Daemon. Apr 15 12:35:16 volumio sudo[1242]: pam_unix(sudo:session): session closed for user root Apr 15 12:35:16 volumio systemd[1]: Starting Volspotconnect2 Daemon... Apr 15 12:35:16 volumio systemd[1]: Started Volspotconnect2 Daemon. Apr 15 12:35:16 volumio sudo[1250]: pam_unix(sudo:session): session closed for user root Apr 15 12:35:16 volumio volumio[1252]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Apr 15 12:35:16 volumio volumio[1252]: Reading Config from "/tmp/volspotify.toml" Apr 15 12:35:16 volumio volumio[1252]: Malformed config key: missing field `Authentication` Apr 15 12:35:16 volumio volumio[1260]: INFO:librespot: librespot 9b85d33 (2018-05-18). Built on 2018-05-18. Build ID: u91GgpUM Apr 15 12:35:16 volumio volumio[1260]: WARN:mdns: Failed to register IPv6 receiver: Error { repr: Os { code: 19, message: "No such device" } } Apr 15 12:35:16 volumio volumio[1115]: info: Loading plugin "streaming_services"... Apr 15 12:35:16 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 12:35:16 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 12:35:16 volumio volumio[1115]: info: Streaming services startup Apr 15 12:35:17 volumio volumio[1115]: info: Loading i18n strings for locale it Apr 15 12:35:17 volumio volumio[1115]: Updating browse sources language Apr 15 12:35:17 volumio volumio[1115]: Cannot find translation for sourceMedia Servers Apr 15 12:35:17 volumio sudo[1268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 15 12:35:17 volumio sudo[1268]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:35:17 volumio systemd[1]: Stopping Volumio Streaming Daemon... Apr 15 12:35:17 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 12:35:17 volumio volumio[1115]: info: CoreCommandRouter::initPlayerControls Apr 15 12:35:17 volumio systemd[1]: Starting Volumio Streaming Daemon... Apr 15 12:35:17 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 12:35:17 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Apr 15 12:35:17 volumio systemd[1]: Started Volumio Streaming Daemon. Apr 15 12:35:17 volumio sudo[1268]: pam_unix(sudo:session): session closed for user root Apr 15 12:35:17 volumio volumio-streaming-daemon[1274]: ############################ Apr 15 12:35:17 volumio volumio-streaming-daemon[1274]: # Volumio Streaming Daemon # Apr 15 12:35:17 volumio volumio-streaming-daemon[1274]: # Running on port 7777 # Apr 15 12:35:17 volumio volumio-streaming-daemon[1274]: ############################ Apr 15 12:35:17 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 12:35:17 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 12:35:17 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 12:35:17 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 12:35:17 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 12:35:17 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 12:35:17 volumio volumio[1115]: info: BOOT COMPLETED Apr 15 12:35:17 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 12:35:17 volumio wpa_supplicant[884]: wlan0: Failed to initiate sched scan Apr 15 12:35:18 volumio systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Apr 15 12:35:18 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Apr 15 12:35:18 volumio systemd[1]: Starting Volspotconnect2 Daemon... Apr 15 12:35:18 volumio systemd[1]: Started Volspotconnect2 Daemon. Apr 15 12:35:18 volumio volumio[1286]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Apr 15 12:35:18 volumio volumio[1286]: Reading Config from "/tmp/volspotify.toml" Apr 15 12:35:18 volumio volumio[1286]: Malformed config key: missing field `Authentication` Apr 15 12:35:20 volumio startx[1117]: xinit: giving up Apr 15 12:35:20 volumio startx[1117]: xinit: unable to connect to X server: Cannot assign requested address Apr 15 12:35:20 volumio startx[1117]: xinit: server error Apr 15 12:35:20 volumio systemd[1]: volumio-kiosk.service: main process exited, code=exited, status=1/FAILURE Apr 15 12:35:20 volumio systemd[1]: Unit volumio-kiosk.service entered failed state. Apr 15 12:35:20 volumio systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Apr 15 12:35:20 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Apr 15 12:35:20 volumio systemd[1]: Starting Volspotconnect2 Daemon... Apr 15 12:35:20 volumio systemd[1]: Started Volspotconnect2 Daemon. Apr 15 12:35:20 volumio volumio[1292]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Apr 15 12:35:20 volumio volumio[1292]: Reading Config from "/tmp/volspotify.toml" Apr 15 12:35:20 volumio volumio[1292]: Malformed config key: missing field `Authentication` Apr 15 12:35:22 volumio volumio[1115]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Apr 15 12:35:22 volumio volumio[1115]: info: CoreCommandRouter::Close All Modals sent Apr 15 12:35:22 volumio volumio[1115]: info: CoreCommandRouter::Close All Modals sent Apr 15 12:35:22 volumio volumio[1115]: Express server listening on port 3000 Apr 15 12:35:22 volumio volumio[1115]: Volumio Calling Home Apr 15 12:35:22 volumio volumio[1115]: info: Volsimpleequal Started Apr 15 12:35:22 volumio volumio[1115]: info: MyVolumio not started Apr 15 12:35:22 volumio volumio[1115]: info: CoreStateMachine::resetVolumioState Apr 15 12:35:22 volumio volumio[1115]: info: CoreStateMachine::getcurrentVolume Apr 15 12:35:22 volumio volumio[1115]: info: CoreCommandRouter::volumioRetrievevolume Apr 15 12:35:22 volumio volumio[1115]: info: CoreStateMachine::updateTrackBlock Apr 15 12:35:22 volumio volumio[1115]: info: CorePlayQueue::getTrackBlock Apr 15 12:35:22 volumio volumio[1115]: info: CoreStateMachine::pushState Apr 15 12:35:22 volumio volumio[1115]: info: CoreStateMachine::getState Apr 15 12:35:22 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Apr 15 12:35:22 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Apr 15 12:35:22 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 12:35:22 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , disableI2SDAC Apr 15 12:35:22 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 12:35:22 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 15 12:35:22 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 15 12:35:22 volumio volumio[1115]: info: Output device has changed, restarting MPD Apr 15 12:35:22 volumio sudo[1303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 15 12:35:22 volumio volumio[1115]: info: Output device has changed, restarting Shairport Sync Apr 15 12:35:22 volumio volumio[1115]: info: wwwwwwwwwwwwwwwwWWWWWWWWWWWWWWWWwwwwwwwwwwwWWWWWWWWWwwwwwwwwwwWWWWWwwOutput device has changed, continuing config Apr 15 12:35:22 volumio sudo[1303]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:35:22 volumio sudo[1303]: pam_unix(sudo:session): session closed for user root Apr 15 12:35:22 volumio sudo[1306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 12:35:22 volumio sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:35:22 volumio systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Apr 15 12:35:22 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Apr 15 12:35:22 volumio systemd[1]: Starting Volspotconnect2 Daemon... Apr 15 12:35:22 volumio systemd[1]: Started Volspotconnect2 Daemon. Apr 15 12:35:22 volumio systemd[1]: Stopping Music Player Daemon... Apr 15 12:35:22 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 15 12:35:22 volumio volumio[1320]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Apr 15 12:35:22 volumio volumio[1320]: Reading Config from "/tmp/volspotify.toml" Apr 15 12:35:22 volumio volumio[1320]: Malformed config key: missing field `Authentication` Apr 15 12:35:22 volumio volumio[1115]: info: Device Loopback does not have any Mixer Control Available, setting a softvol device Apr 15 12:35:22 volumio volumio[1115]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 15 12:35:22 volumio volumio[1115]: info: Updating Volume Controller Parameters: Device: Loopback Name: Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Apr 15 12:35:22 volumio volumio[1115]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 12:35:22 volumio volumio[1115]: info: CoreStateMachine::pushState Apr 15 12:35:22 volumio volumio[1115]: info: CoreStateMachine::getState Apr 15 12:35:22 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Apr 15 12:35:22 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Apr 15 12:35:22 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 12:35:22 volumio sudo[1327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 15 12:35:22 volumio sudo[1327]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:35:22 volumio sudo[1327]: pam_unix(sudo:session): session closed for user root Apr 15 12:35:22 volumio systemd[1]: Starting Music Player Daemon... Apr 15 12:35:23 volumio volumio[1115]: info: MPD Permissions set Apr 15 12:35:23 volumio volumio[1115]: info: Setting Device type: Raspberry PI Apr 15 12:35:23 volumio volumio[1115]: info: Volspotconnect2 Daemon Started Apr 15 12:35:23 volumio volumio[1115]: info: [SpConDebug] Volspotconnect2 Started Apr 15 12:35:23 volumio volumio[1115]: info: [SpConDebug] Starting metadata listener Apr 15 12:35:23 volumio volumio[1115]: { Error: bind EADDRINUSE 0.0.0.0:5030 Apr 15 12:35:23 volumio volumio[1115]: at Object._errnoException (util.js:1022:11) Apr 15 12:35:23 volumio volumio[1115]: at _exceptionWithHostPort (util.js:1044:20) Apr 15 12:35:23 volumio volumio[1115]: at _handle.lookup (dgram.js:266:18) Apr 15 12:35:23 volumio volumio[1115]: at _combinedTickCallback (internal/process/next_tick.js:141:11) Apr 15 12:35:23 volumio volumio[1115]: at process._tickCallback (internal/process/next_tick.js:180:9) Apr 15 12:35:23 volumio volumio[1115]: code: 'EADDRINUSE', Apr 15 12:35:23 volumio volumio[1115]: errno: 'EADDRINUSE', Apr 15 12:35:23 volumio volumio[1115]: syscall: 'bind', Apr 15 12:35:23 volumio volumio[1115]: address: '0.0.0.0', Apr 15 12:35:23 volumio volumio[1115]: port: 5030 } Apr 15 12:35:23 volumio systemd[1]: Started Music Player Daemon. Apr 15 12:35:23 volumio volumio[1115]: [SpotifyConnect] Vollibrespot Daemon service started! Apr 15 12:35:23 volumio volumio[1115]: SpotifyConnect: 7179.531ms Apr 15 12:35:23 volumio volumio[1115]: info: snd_aloop loaded Apr 15 12:35:23 volumio volumio[1115]: info: touch_display: systemctl daemon-reload succeeded. Apr 15 12:35:23 volumio sudo[1306]: pam_unix(sudo:session): session closed for user root Apr 15 12:35:23 volumio sudo[1345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Apr 15 12:35:23 volumio sudo[1345]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:35:23 volumio volumio[1115]: info: MPD running with PID568 ,establishing connection Apr 15 12:35:23 volumio systemd[1]: Starting Volumio Kiosk... Apr 15 12:35:23 volumio systemd[1]: Started Volumio Kiosk. Apr 15 12:35:23 volumio sudo[1351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Apr 15 12:35:23 volumio sudo[1345]: pam_unix(sudo:session): session closed for user root Apr 15 12:35:23 volumio sudo[1351]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:35:23 volumio sudo[1351]: pam_unix(sudo:session): session closed for user root Apr 15 12:35:23 volumio startx[1353]: X.Org X Server 1.18.4 Apr 15 12:35:23 volumio startx[1353]: Release Date: 2016-07-19 Apr 15 12:35:23 volumio startx[1353]: X Protocol Version 11, Revision 0 Apr 15 12:35:23 volumio startx[1353]: Build Operating System: Linux 4.4.26-v7+ armv7l Raspbian Apr 15 12:35:23 volumio startx[1353]: Current Operating System: Linux volumio 4.14.71-v7+ #1145 SMP Fri Sep 21 15:38:35 BST 2018 armv7l Apr 15 12:35:23 volumio startx[1353]: Kernel command line: 8250.nr_uarts=0 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash quiet plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 console=ttyS0,115200 kgdboc=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh elevator=noop rootwait bootdelay=5 logo.nologo vt.global_cursor_default=0 loglevel=0 Apr 15 12:35:23 volumio startx[1353]: Build Date: 11 November 2016 11:59:59AM Apr 15 12:35:23 volumio startx[1353]: xorg-server 2:1.18.4-2+rpi1 (https://www.debian.org/support) Apr 15 12:35:23 volumio startx[1353]: Current version of pixman: 0.32.6 Apr 15 12:35:23 volumio startx[1353]: Before reporting problems, check http://wiki.x.org Apr 15 12:35:23 volumio startx[1353]: to make sure that you have the latest version. Apr 15 12:35:23 volumio startx[1353]: Markers: (--) probed, (**) from config file, (==) default setting, Apr 15 12:35:23 volumio startx[1353]: (++) from command line, (!!) notice, (II) informational, Apr 15 12:35:23 volumio startx[1353]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Apr 15 12:35:23 volumio startx[1353]: (==) Log file: "/home/volumio/.local/share/xorg/Xorg.0.log", Time: Mon Apr 15 12:35:23 2024 Apr 15 12:35:23 volumio startx[1353]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Apr 15 12:35:23 volumio startx[1353]: (EE) Apr 15 12:35:23 volumio startx[1353]: Fatal server error: Apr 15 12:35:23 volumio startx[1353]: (EE) parse_vt_settings: Cannot open /dev/tty0 (Permission denied) Apr 15 12:35:23 volumio startx[1353]: (EE) Apr 15 12:35:23 volumio startx[1353]: (EE) Apr 15 12:35:23 volumio startx[1353]: Please consult the The X.Org Foundation support Apr 15 12:35:23 volumio startx[1353]: at http://wiki.x.org Apr 15 12:35:23 volumio startx[1353]: for help. Apr 15 12:35:23 volumio startx[1353]: (EE) Please also check the log file at "/home/volumio/.local/share/xorg/Xorg.0.log" for additional information. Apr 15 12:35:23 volumio startx[1353]: (EE) Apr 15 12:35:23 volumio startx[1353]: (EE) Server terminated with error (1). Closing log file. Apr 15 12:35:23 volumio volumio[1115]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 12:35:23 volumio volumio[1115]: { AssertionError [ERR_ASSERTION]: false == true Apr 15 12:35:23 volumio volumio[1115]: at MpdClient.sendCommand (/volumio/app/plugins/music_service/mpd/lib/mpd.js:92:9) Apr 15 12:35:23 volumio volumio[1115]: at /volumio/app/plugins/music_service/mpd/index.js:2998:19 Apr 15 12:35:23 volumio volumio[1115]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Apr 15 12:35:23 volumio volumio[1115]: at process._tickCallback (internal/process/next_tick.js:180:9) Apr 15 12:35:23 volumio volumio[1115]: generatedMessage: true, Apr 15 12:35:23 volumio volumio[1115]: name: 'AssertionError [ERR_ASSERTION]', Apr 15 12:35:23 volumio volumio[1115]: code: 'ERR_ASSERTION', Apr 15 12:35:23 volumio volumio[1115]: actual: false, Apr 15 12:35:23 volumio volumio[1115]: expected: true, Apr 15 12:35:23 volumio volumio[1115]: operator: '==' } Apr 15 12:35:23 volumio volumio[1115]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 12:35:24 volumio sudo[1394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-15 12:34 Apr 15 12:35:24 volumio sudo[1394]: 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="c52efa8090336a1aa08c5caa4d93eb926a1a7bed" VOLUMIO_FE_VERSION="97a49ba890ceb3037f74abfccc14d256050d3ef6" VOLUMIO_BE_VERSION="8bb3137b5d493584edfbdbea0fb0075f47d9c7f6" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 31 12:58:00 CET 2018" VOLUMIO_VERSION="2.502" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="d0847620f095b456556d6c09b0a4a5d4"