-- Logs begin at Mon 2024-10-28 16:49:22 UTC, end at Mon 2024-10-28 16:52:10 UTC. -- Oct 28 16:51:00 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 28 16:51:00 restefuschiboomboom sudo[2074]: pam_unix(sudo:session): session closed for user root Oct 28 16:51:00 restefuschiboomboom volumio[1540]: info: touch_display: systemctl daemon-reload succeeded. Oct 28 16:51:00 restefuschiboomboom sudo[2094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Oct 28 16:51:00 restefuschiboomboom sudo[2094]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 16:51:00 restefuschiboomboom systemd[1]: Starting Volumio Kiosk... Oct 28 16:51:00 restefuschiboomboom systemd[1]: Started Volumio Kiosk. Oct 28 16:51:00 restefuschiboomboom startx[2099]: X.Org X Server 1.18.4 Oct 28 16:51:00 restefuschiboomboom startx[2099]: Release Date: 2016-07-19 Oct 28 16:51:00 restefuschiboomboom startx[2099]: X Protocol Version 11, Revision 0 Oct 28 16:51:00 restefuschiboomboom startx[2099]: Build Operating System: Linux 4.4.26-v7+ armv7l Raspbian Oct 28 16:51:00 restefuschiboomboom startx[2099]: Current Operating System: Linux restefuschiboomboom 4.19.118-v7+ #1311 SMP Mon Apr 27 14:21:24 BST 2020 armv7l Oct 28 16:51:00 restefuschiboomboom startx[2099]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 bcm2708_fb.fbwidth=640 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 fbcon=map:10 Oct 28 16:51:00 restefuschiboomboom startx[2099]: Build Date: 11 November 2016 11:59:59AM Oct 28 16:51:00 restefuschiboomboom startx[2099]: xorg-server 2:1.18.4-2+rpi1 (https://www.debian.org/support) Oct 28 16:51:00 restefuschiboomboom startx[2099]: Current version of pixman: 0.32.6 Oct 28 16:51:00 restefuschiboomboom startx[2099]: Before reporting problems, check http://wiki.x.org Oct 28 16:51:00 restefuschiboomboom startx[2099]: to make sure that you have the latest version. Oct 28 16:51:00 restefuschiboomboom startx[2099]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 28 16:51:00 restefuschiboomboom startx[2099]: (++) from command line, (!!) notice, (II) informational, Oct 28 16:51:00 restefuschiboomboom startx[2099]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 28 16:51:00 restefuschiboomboom startx[2099]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Oct 28 16:51:00 2024 Oct 28 16:51:00 restefuschiboomboom startx[2099]: (==) Using config directory: "/etc/X11/xorg.conf.d" Oct 28 16:51:00 restefuschiboomboom startx[2099]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 28 16:51:00 restefuschiboomboom startx[2099]: modprobe: FATAL: Module g2d_23 not found. Oct 28 16:51:00 restefuschiboomboom sudo[2094]: pam_unix(sudo:session): session closed for user root Oct 28 16:51:00 restefuschiboomboom volumio[1540]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Oct 28 16:51:00 restefuschiboomboom volumio[1540]: info: touch_display: Volumio Kiosk started Oct 28 16:51:00 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 16:51:00 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::volumioGetState Oct 28 16:51:00 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 0 Oct 28 16:51:00 restefuschiboomboom sudo[2127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=pi,password=A0ETH02CL001,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //PIMATICSERVER/MediaFreigabe/MEDIA/Spotify Musik/ /mnt/NAS/MediaFreigabe Oct 28 16:51:00 restefuschiboomboom sudo[2127]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 16:51:00 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::volumioGetState Oct 28 16:51:00 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 0 Oct 28 16:51:00 restefuschiboomboom volumio[1540]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 28 16:51:00 restefuschiboomboom volumio[1540]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Oct 28 16:51:00 restefuschiboomboom sudo[2146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e /Option "TransformationMatrix"/d /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Oct 28 16:51:00 restefuschiboomboom sudo[2146]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 16:51:00 restefuschiboomboom sudo[2146]: pam_unix(sudo:session): session closed for user root Oct 28 16:51:00 restefuschiboomboom volumio[1540]: info: touch_display: Touchscreen transformation matrix removed. Oct 28 16:51:01 restefuschiboomboom sudo[2127]: pam_unix(sudo:session): session closed for user root Oct 28 16:51:01 restefuschiboomboom volumio[1540]: error: touch_display: Error setting screensaver timeout: Error: Command failed: /usr/bin/xset -display :0 s off +dpms dpms 0 0 0 Oct 28 16:51:01 restefuschiboomboom volumio[1540]: /usr/bin/xset: unable to open display ":0" Oct 28 16:51:01 restefuschiboomboom volumio[1540]: info: Cannot mount NAS MediaFreigabe at system boot, trial number 2 ,retrying in 5 seconds Oct 28 16:51:01 restefuschiboomboom startx[2099]: waiting for X server to begin accepting connections modprobe: FATAL: Module g2d_23 not found. Oct 28 16:51:02 restefuschiboomboom startx[2099]: . Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: Adding plugin bluetooth to MyMusic Plugins Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: Adding plugin cd_controller to MyMusic Plugins Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 28 16:51:03 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 28 16:51:04 restefuschiboomboom volumio-remote-updater[761]: [2024-10-28 16:51:04] [connect] Successful connection Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: Starting MyVolumio Remote Streaming Endpoints Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: Streaming services startup Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: Starting Streaming Daemon Oct 28 16:51:05 restefuschiboomboom sudo[2263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 28 16:51:05 restefuschiboomboom sudo[2263]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: MyVolumio not started Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: Initializing device activation check Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: Enabling MyVolumio plan superstar via Activation Code and Device Method - Enable: cdc4fc6a350b7bd580238ff62123b7dd Oct 28 16:51:05 restefuschiboomboom systemd[1]: Stopping Volumio Streaming Daemon... Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 28 16:51:05 restefuschiboomboom systemd[1]: Starting Volumio Streaming Daemon... Oct 28 16:51:05 restefuschiboomboom systemd[1]: Started Volumio Streaming Daemon. Oct 28 16:51:05 restefuschiboomboom sudo[2263]: pam_unix(sudo:session): session closed for user root Oct 28 16:51:05 restefuschiboomboom volumio-streaming-daemon[2273]: ############################ Oct 28 16:51:05 restefuschiboomboom volumio-streaming-daemon[2273]: # Volumio Streaming Daemon # Oct 28 16:51:05 restefuschiboomboom volumio-streaming-daemon[2273]: # Running on port 7777 # Oct 28 16:51:05 restefuschiboomboom volumio-streaming-daemon[2273]: ############################ Oct 28 16:51:05 restefuschiboomboom volumio-streaming-daemon[2273]: Environment: production Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: ControllerAutoPlay - getting queue Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::volumioGetQueue Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: CoreStateMachine::getQueue Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getQueue Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: ControllerAutoPlay - start playing -> queue is not empty Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::volumioPlay Oct 28 16:51:05 restefuschiboomboom volumio[1540]: verbose: UNSET VOLATILE Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: CoreStateMachine::play index undefined Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 0 Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: CoreStateMachine::startPlaybackTimer Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 0 Oct 28 16:51:05 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::clearAddPlayTracks NAS/MediaFreigabe/Bonaparte/Bonaparte/1-800.mp3 Oct 28 16:51:05 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::sendMpdCommand stop Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: sendMpdCommand stop took 35 milliseconds Oct 28 16:51:05 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::sendMpdCommand clear Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: Oct 28 16:51:05 restefuschiboomboom volumio[1540]: ---------------------------- MPD announces system playlist update Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: Ignoring MPD Status Update Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: sendMpdCommand clear took 14 milliseconds Oct 28 16:51:05 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::sendMpdCommand add "NAS/MediaFreigabe/Bonaparte/Bonaparte/1-800.mp3" Oct 28 16:51:05 restefuschiboomboom volumio-remote-updater[761]: [2024-10-28 16:51:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1730134264 101 Oct 28 16:51:05 restefuschiboomboom volumio[1540]: error: updateQueue error: null Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: Oct 28 16:51:05 restefuschiboomboom volumio[1540]: ---------------------------- MPD announces system playlist update Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: Ignoring MPD Status Update Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: ------------------------------ 32ms Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: sendMpdCommand add "NAS/MediaFreigabe/Bonaparte/Bonaparte/1-800.mp3" took 27 milliseconds Oct 28 16:51:05 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::sendMpdCommand play Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: Remote config written successfully Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: ------------------------------ 20ms Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: Oct 28 16:51:05 restefuschiboomboom volumio[1540]: ---------------------------- MPD announces state update: player Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: ControllerMpd::getState Oct 28 16:51:05 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::sendMpdCommand status Oct 28 16:51:05 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 28 16:51:05 restefuschiboomboom volumio[1540]: info: sendMpdCommand status took 18 milliseconds Oct 28 16:51:05 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::parseState Oct 28 16:51:05 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: sendMpdCommand clearerror took 22 milliseconds Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 28 16:51:06 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::parseTrackInfo Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: ControllerMpd::pushState Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::servicePushState Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: CoreStateMachine::pushState Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 0 Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::volumioPushState Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 0 Oct 28 16:51:06 restefuschiboomboom volumio[1540]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1-800","artist":"Bonaparte","album":"Bonaparte","uri":"NAS/MediaFreigabe/Bonaparte/Bonaparte/1-800.mp3","trackType":"mp3"} Oct 28 16:51:06 restefuschiboomboom volumio[1540]: verbose: CURRENT POSITION 0 Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: CoreStateMachine::syncState stateService stop Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: CoreStateMachine::syncState currentStatus stop Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: CoreStateMachine::pushState Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 0 Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::volumioPushState Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: No code Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: CoreStateMachine::pushState Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 0 Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::volumioPushState Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: ------------------------------ 117ms Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: [ASDebug] CurState: stop PrevState: stop Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: [ASDebug] CurState: stop PrevState: stop Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: [ASDebug] CurState: stop PrevState: stop Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: [MyVolumio PluginManager] No plugins found for user plan free Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: MyVolumio status changed Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: Streaming services startup Oct 28 16:51:06 restefuschiboomboom volumio[1540]: info: Re-Starting Streaming Daemon Oct 28 16:51:06 restefuschiboomboom sudo[2285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=pi,password=A0ETH02CL001,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //PIMATICSERVER/MediaFreigabe/MEDIA/Spotify Musik/ /mnt/NAS/MediaFreigabe Oct 28 16:51:06 restefuschiboomboom sudo[2285]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 16:51:06 restefuschiboomboom volumio[1540]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' Oct 28 16:51:06 restefuschiboomboom volumio-streaming-daemon[2273]: Restarting daemon Oct 28 16:51:06 restefuschiboomboom volumio-streaming-daemon[2273]: Environment: production Oct 28 16:51:07 restefuschiboomboom sudo[2285]: pam_unix(sudo:session): session closed for user root Oct 28 16:51:07 restefuschiboomboom volumio[1540]: info: Cannot mount NAS MediaFreigabe at system boot, trial number 3 ,retrying in 5 seconds Oct 28 16:51:08 restefuschiboomboom volumio-streaming-daemon[2273]: Environment: production Oct 28 16:51:08 restefuschiboomboom volumio-streaming-daemon[2273]: Environment: production Oct 28 16:51:08 restefuschiboomboom volumio[1540]: error: [MyVolumio PluginManager] Could not fetch plugin keys: {"success":false,"error":{"message":"DEVICE_NOT_FOUND"}} Oct 28 16:51:10 restefuschiboomboom volumio[1540]: info: Fetching Streaming Services browse cache Oct 28 16:51:10 restefuschiboomboom volumio[1540]: info: Fetching Streaming Services browse cache Oct 28 16:51:12 restefuschiboomboom sudo[2327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=pi,password=A0ETH02CL001,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //PIMATICSERVER/MediaFreigabe/MEDIA/Spotify Musik/ /mnt/NAS/MediaFreigabe Oct 28 16:51:12 restefuschiboomboom sudo[2327]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 16:51:12 restefuschiboomboom sudo[2327]: pam_unix(sudo:session): session closed for user root Oct 28 16:51:13 restefuschiboomboom volumio[1540]: info: Cannot mount NAS MediaFreigabe at system boot, trial number 4 ,retrying in 5 seconds Oct 28 16:51:16 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 28 16:51:16 restefuschiboomboom volumio[1540]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 28 16:51:16 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 28 16:51:16 restefuschiboomboom volumio[1540]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Oct 28 16:51:16 restefuschiboomboom volumio[1540]: info: Received Get System Version Oct 28 16:51:16 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 28 16:51:18 restefuschiboomboom sudo[2347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=pi,password=A0ETH02CL001,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //PIMATICSERVER/MediaFreigabe/MEDIA/Spotify Musik/ /mnt/NAS/MediaFreigabe Oct 28 16:51:18 restefuschiboomboom sudo[2347]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 16:51:18 restefuschiboomboom sudo[2347]: pam_unix(sudo:session): session closed for user root Oct 28 16:51:20 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 28 16:51:20 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 16:51:20 restefuschiboomboom volumio[1540]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 28 16:51:23 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::volumioGetState Oct 28 16:51:23 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 0 Oct 28 16:51:25 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 28 16:51:25 restefuschiboomboom volumio[1540]: info: Listing playlists Oct 28 16:51:25 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 28 16:51:25 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 28 16:51:25 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 28 16:51:25 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 28 16:51:28 restefuschiboomboom volumio[1991]: [Vollibrespot] : Could not connect to server: Connection timed out (os error 110) Oct 28 16:51:31 restefuschiboomboom kernel: Voltage normalised (0x00000000) Oct 28 16:51:40 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 28 16:51:40 restefuschiboomboom volumio[1540]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 28 16:51:40 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 28 16:51:40 restefuschiboomboom volumio[1540]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Oct 28 16:51:40 restefuschiboomboom volumio[1540]: info: Received Get System Version Oct 28 16:51:40 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 28 16:51:43 restefuschiboomboom volumio[1540]: info: Downloading plugin at http://plugins.volumio.org/plugins/volumio/armhf/miscellanea/ampswitch/ampswitch.zip Oct 28 16:51:47 restefuschiboomboom volumio[1540]: info: END DOWNLOAD: http://plugins.volumio.org/plugins/volumio/armhf/miscellanea/ampswitch/ampswitch.zip Oct 28 16:51:48 restefuschiboomboom volumio[1540]: info: [ASDebug] Port: 19 Oct 28 16:51:48 restefuschiboomboom volumio[1540]: info: [ASDebug] Inverted: false Oct 28 16:51:48 restefuschiboomboom volumio[1540]: info: [ASDebug] Delay: 650 Oct 28 16:51:53 restefuschiboomboom sudo[2389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 28 16:51:53 restefuschiboomboom sudo[2389]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 16:51:53 restefuschiboomboom sudo[2389]: pam_unix(sudo:session): session closed for user root Oct 28 16:51:53 restefuschiboomboom sudo[2392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 28 16:51:53 restefuschiboomboom sudo[2392]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 16:51:53 restefuschiboomboom sudo[2392]: pam_unix(sudo:session): session closed for user root Oct 28 16:51:53 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::volumioGetState Oct 28 16:51:53 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 0 Oct 28 16:51:53 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 28 16:51:53 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 28 16:51:53 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 28 16:51:53 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 28 16:51:55 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 28 16:51:57 restefuschiboomboom sudo[2411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 28 16:51:57 restefuschiboomboom sudo[2411]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 16:51:57 restefuschiboomboom sudo[2414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 28 16:51:57 restefuschiboomboom sudo[2411]: pam_unix(sudo:session): session closed for user root Oct 28 16:51:57 restefuschiboomboom sudo[2414]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 16:51:57 restefuschiboomboom sudo[2414]: pam_unix(sudo:session): session closed for user root Oct 28 16:51:57 restefuschiboomboom sudo[2429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 28 16:51:57 restefuschiboomboom sudo[2429]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 16:51:57 restefuschiboomboom sudo[2429]: pam_unix(sudo:session): session closed for user root Oct 28 16:51:57 restefuschiboomboom sudo[2432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 28 16:51:57 restefuschiboomboom sudo[2432]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 16:51:57 restefuschiboomboom sudo[2432]: pam_unix(sudo:session): session closed for user root Oct 28 16:51:57 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::volumioGetState Oct 28 16:51:57 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 0 Oct 28 16:51:57 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 28 16:51:57 restefuschiboomboom volumio[1540]: info: Listing playlists Oct 28 16:51:57 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 28 16:51:57 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 28 16:51:57 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 28 16:51:57 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::volumioPlay Oct 28 16:51:59 restefuschiboomboom volumio[1540]: verbose: UNSET VOLATILE Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: CoreStateMachine::play index undefined Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 0 Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: CoreStateMachine::startPlaybackTimer Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 0 Oct 28 16:51:59 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::clearAddPlayTracks NAS/MediaFreigabe/Bonaparte/Bonaparte/1-800.mp3 Oct 28 16:51:59 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::sendMpdCommand stop Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: sendMpdCommand stop took 23 milliseconds Oct 28 16:51:59 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::sendMpdCommand clear Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: Oct 28 16:51:59 restefuschiboomboom volumio[1540]: ---------------------------- MPD announces system playlist update Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: Ignoring MPD Status Update Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: sendMpdCommand clear took 5 milliseconds Oct 28 16:51:59 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::sendMpdCommand add "NAS/MediaFreigabe/Bonaparte/Bonaparte/1-800.mp3" Oct 28 16:51:59 restefuschiboomboom volumio[1540]: error: updateQueue error: null Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: Oct 28 16:51:59 restefuschiboomboom volumio[1540]: ---------------------------- MPD announces system playlist update Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: Ignoring MPD Status Update Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: ------------------------------ 11ms Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: sendMpdCommand add "NAS/MediaFreigabe/Bonaparte/Bonaparte/1-800.mp3" took 7 milliseconds Oct 28 16:51:59 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::sendMpdCommand play Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: Oct 28 16:51:59 restefuschiboomboom volumio[1540]: ---------------------------- MPD announces state update: player Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: ------------------------------ 14ms Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: sendMpdCommand play took 9 milliseconds Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: ControllerMpd::getState Oct 28 16:51:59 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::sendMpdCommand status Oct 28 16:51:59 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: sendMpdCommand status took 18 milliseconds Oct 28 16:51:59 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::parseState Oct 28 16:51:59 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: sendMpdCommand clearerror took 8 milliseconds Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 28 16:51:59 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::parseTrackInfo Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: ControllerMpd::pushState Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::servicePushState Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: CoreStateMachine::pushState Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 0 Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::volumioPushState Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 0 Oct 28 16:51:59 restefuschiboomboom volumio[1540]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1-800","artist":"Bonaparte","album":"Bonaparte","uri":"NAS/MediaFreigabe/Bonaparte/Bonaparte/1-800.mp3","trackType":"mp3"} Oct 28 16:51:59 restefuschiboomboom volumio[1540]: verbose: CURRENT POSITION 0 Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: CoreStateMachine::syncState stateService stop Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: CoreStateMachine::syncState currentStatus stop Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: CoreStateMachine::pushState Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 0 Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::volumioPushState Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: No code Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: CoreStateMachine::pushState Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 0 Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::volumioPushState Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: ------------------------------ 127ms Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: [ASDebug] CurState: stop PrevState: stop Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: [ASDebug] CurState: stop PrevState: stop Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: [ASDebug] CurState: stop PrevState: stop Oct 28 16:51:59 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 28 16:52:03 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::volumioGetVisibleSources Oct 28 16:52:03 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 16:52:03 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::volumioGetQueue Oct 28 16:52:03 restefuschiboomboom volumio[1540]: info: CoreStateMachine::getQueue Oct 28 16:52:03 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getQueue Oct 28 16:52:04 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , handleBrowseUri Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CoreStateMachine::ClearQueue Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CoreStateMachine::stop Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 16:52:06 restefuschiboomboom volumio[1540]: verbose: UNSET VOLATILE Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CorePlayQueue::clearPlayQueue Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CorePlayQueue::saveQueue Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::volumioPushState Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::volumioPushQueue Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CoreStateMachine::addQueueItems Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CorePlayQueue::addQueueItems Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: Adding Item to queue: webrp/0 Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: Exploding uri webrp/0 in service radio_paradise Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CorePlayQueue::saveQueue Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::volumioPushQueue Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CoreStateMachine::updateTrackBlock Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrackBlock Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::volumioPlay Oct 28 16:52:06 restefuschiboomboom volumio[1540]: verbose: UNSET VOLATILE Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CoreStateMachine::play index 0 Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CoreStateMachine::stop Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 16:52:06 restefuschiboomboom volumio[1540]: verbose: UNSET VOLATILE Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CoreStateMachine::play index undefined Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 0 Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CoreStateMachine::startPlaybackTimer Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 0 Oct 28 16:52:06 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::sendMpdCommand stop Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: [ASDebug] CurState: stop PrevState: stop Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: sendMpdCommand stop took 42 milliseconds Oct 28 16:52:06 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::sendMpdCommand clear Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: Oct 28 16:52:06 restefuschiboomboom volumio[1540]: ---------------------------- MPD announces system playlist update Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: Ignoring MPD Status Update Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: sendMpdCommand clear took 3 milliseconds Oct 28 16:52:06 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 28 16:52:06 restefuschiboomboom volumio[1540]: error: updateQueue error: null Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: Oct 28 16:52:06 restefuschiboomboom volumio[1540]: ---------------------------- MPD announces state update: options Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: ------------------------------ 14ms Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: sendMpdCommand consume 1 took 11 milliseconds Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: ControllerMpd::getState Oct 28 16:52:06 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::sendMpdCommand status Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: [1730134326733] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flac Oct 28 16:52:06 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flac" Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: Oct 28 16:52:06 restefuschiboomboom volumio[1540]: ---------------------------- MPD announces system playlist update Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: Ignoring MPD Status Update Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: sendMpdCommand status took 5 milliseconds Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: sendMpdCommand add "https://stream.radioparadise.com/flac" took 4 milliseconds Oct 28 16:52:06 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::parseState Oct 28 16:52:06 restefuschiboomboom volumio[1540]: verbose: ControllerMpd::sendMpdCommand play Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: ControllerMpd::pushState Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::servicePushState Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CoreStateMachine::pushState Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 0 Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::volumioPushState Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 0 Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: ------------------------------ 40ms Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: ------------------------------ 36ms Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: sendMpdCommand play took 24 milliseconds Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: [1730134326776] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 Oct 28 16:52:06 restefuschiboomboom volumio[1540]: info: [ASDebug] CurState: stop PrevState: stop Oct 28 16:52:08 restefuschiboomboom volumio[1540]: info: [1730134328710] [RadioParadise] received new metadata: {"time":131,"artist":"Medium Build","title":"Yoke (w/ Julien Baker)","album":"Yoke - Single","year":"2024","cover":"https://img.radioparadise.com/covers/l/19546_1a0f8cd6-4e8f-46f0-929b-d9023a3b07d0.jpg","cover_med":"https://img.radioparadise.com/covers/m/19546_1a0f8cd6-4e8f-46f0-929b-d9023a3b07d0.jpg","cover_small":"https://img.radioparadise.com/covers/s/19546_1a0f8cd6-4e8f-46f0-929b-d9023a3b07d0.jpg"} Oct 28 16:52:08 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 0 Oct 28 16:52:08 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::servicePushState Oct 28 16:52:08 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 0 Oct 28 16:52:08 restefuschiboomboom volumio[1540]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/19546_1a0f8cd6-4e8f-46f0-929b-d9023a3b07d0.jpg","uri":"https://stream.radioparadise.com/flac","name":"Yoke (w/ Julien Baker)","title":"Yoke (w/ Julien Baker)","artist":"Medium Build","album":"Yoke - Single","streaming":true,"disableUiControls":true,"duration":136,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Oct 28 16:52:08 restefuschiboomboom volumio[1540]: verbose: CURRENT POSITION 0 Oct 28 16:52:08 restefuschiboomboom volumio[1540]: info: CoreStateMachine::syncState stateService play Oct 28 16:52:08 restefuschiboomboom volumio[1540]: info: CoreStateMachine::syncState currentStatus stop Oct 28 16:52:08 restefuschiboomboom volumio[1540]: info: CoreStateMachine::pushState Oct 28 16:52:08 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 0 Oct 28 16:52:08 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::volumioPushState Oct 28 16:52:08 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 16:52:08 restefuschiboomboom volumio[1540]: info: [1730134328749] [RadioParadise] setting new timer with duration of 136000 seconds. Oct 28 16:52:08 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 0 Oct 28 16:52:08 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getTrack 1 Oct 28 16:52:08 restefuschiboomboom volumio[1540]: info: CoreCommandRouter::volumioGetQueue Oct 28 16:52:08 restefuschiboomboom volumio[1540]: info: CoreStateMachine::getQueue Oct 28 16:52:08 restefuschiboomboom volumio[1540]: info: CorePlayQueue::getQueue Oct 28 16:52:08 restefuschiboomboom volumio[1540]: info: [ASDebug] CurState: play PrevState: stop Oct 28 16:52:08 restefuschiboomboom volumio[1540]: info: [ASDebug] Togle GPIO: ON Oct 28 16:52:08 restefuschiboomboom volumio[1540]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 16:52:08 restefuschiboomboom volumio[1540]: { Error: EBADF: bad file descriptor, write Oct 28 16:52:08 restefuschiboomboom volumio[1540]: at Object.fs.writeSync (fs.js:735:20) Oct 28 16:52:08 restefuschiboomboom volumio[1540]: at Gpio.writeSync (/data/plugins/miscellanea/ampswitch/node_modules/onoff/onoff.js:212:6) Oct 28 16:52:08 restefuschiboomboom volumio[1540]: at AmpSwitchController.on (/data/plugins/miscellanea/ampswitch/index.js:197:23) Oct 28 16:52:08 restefuschiboomboom volumio[1540]: at AmpSwitchController.parseStatus (/data/plugins/miscellanea/ampswitch/index.js:180:86) Oct 28 16:52:08 restefuschiboomboom volumio[1540]: at Socket.Emitter.emit (/data/plugins/miscellanea/ampswitch/node_modules/component-emitter/index.js:133:20) Oct 28 16:52:08 restefuschiboomboom volumio[1540]: at Socket.onevent (/data/plugins/miscellanea/ampswitch/node_modules/socket.io-client/lib/socket.js:270:10) Oct 28 16:52:08 restefuschiboomboom volumio[1540]: at Socket.onpacket (/data/plugins/miscellanea/ampswitch/node_modules/socket.io-client/lib/socket.js:228:12) Oct 28 16:52:08 restefuschiboomboom volumio[1540]: at Manager. (/data/plugins/miscellanea/ampswitch/node_modules/component-bind/index.js:21:15) Oct 28 16:52:08 restefuschiboomboom volumio[1540]: at Manager.Emitter.emit (/data/plugins/miscellanea/ampswitch/node_modules/component-emitter/index.js:133:20) Oct 28 16:52:08 restefuschiboomboom volumio[1540]: at Manager.ondecoded (/data/plugins/miscellanea/ampswitch/node_modules/socket.io-client/lib/manager.js:332:8) errno: -9, code: 'EBADF', syscall: 'write' } Oct 28 16:52:08 restefuschiboomboom volumio[1540]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 16:52:10 restefuschiboomboom sudo[2460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-28 16:51 Oct 28 16:52:10 restefuschiboomboom sudo[2460]: 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"