play playlist crash volumio

Hi All,
When trying to select a playlist and go to “clear and play”, I get the following crash

– Logs begin at Sat 2017-02-04 22:02:02 UTC. –
Feb 04 22:06:47 volumio systemd[1]: Unit airplay.service entered failed state.
Feb 04 22:06:52 volumio sshd[4354]: Accepted publickey for volumio from 192.168.1.102 port 41793 ssh2: RSA 0d:cc:fc:37:21:07:00:78:20:f7:7a:80:0f:54:5d:ce
Feb 04 22:06:52 volumio sshd[4354]: pam_unix(sshd:session): session opened for user volumio by (uid=0)
Feb 04 22:06:52 volumio systemd[1]: Starting Session 2 of user volumio.
Feb 04 22:06:52 volumio systemd[1]: Started Session 2 of user volumio.
Feb 04 22:06:52 volumio systemd-logind[598]: New session 2 of user volumio.
Feb 04 22:06:52 volumio sshd[4354]: lastlog_openseek: Couldn’t stat /var/log/lastlog: No such file or directory
Feb 04 22:06:52 volumio sshd[4354]: lastlog_openseek: Couldn’t stat /var/log/lastlog: No such file or directory
Feb 04 22:06:53 volumio sudo[4374]: volumio : TTY=pts/1 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f
Feb 04 22:06:53 volumio sudo[4374]: pam_unix(sudo:session): session opened for user root by volumio(uid=0)
Feb 04 22:07:10 volumio systemd-journal[217]: Suppressed 34886 messages from /system.slice/volumio.service
Feb 04 22:07:10 volumio volumio[4276]: Missing error handler on socket.
Feb 04 22:07:10 volumio volumio[4276]: TypeError: thisInterface.pushQueue is not a function
Feb 04 22:07:10 volumio volumio[4276]: at /volumio/app/index.js:312:25
Feb 04 22:07:10 volumio volumio[4276]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17)
Feb 04 22:07:10 volumio volumio[4276]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12)
Feb 04 22:07:10 volumio volumio[4276]: at CoreCommandRouter.volumioPushQueue (/volumio/app/index.js:310:11)
Feb 04 22:07:10 volumio volumio[4276]: at CorePlayQueue.clearPlayQueue (/volumio/app/playqueue.js:200:28)
Feb 04 22:07:10 volumio volumio[4276]: at CoreStateMachine.clearQueue (/volumio/app/statemachine.js:153:24)
Feb 04 22:07:10 volumio volumio[4276]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:388:20)
Feb 04 22:07:10 volumio volumio[4276]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:123:24)
Feb 04 22:07:10 volumio volumio[4276]: at emitTwo (events.js:106:13)
Feb 04 22:07:10 volumio volumio[4276]: at Socket.emit (events.js:191:7)

I think we need some more detail here alior. You seem to be using airplay?? Device and version of Volumio? Also, your log seems a bit truncated. Run:

sudo journalctl -f

just before you select the playlist.

hi,
this is using “journalctl -f” … and it’s with latest from git (did git pull --all right before)
no airplay … just picked up few mp3 under a playelist and tried to play it from webgui…
Does it reproduce on your env ? if not I will try to look for something I might have indirectly done (I’m developing a plugin for alexa control and trying to get the plugin to start a playlist wihtout much success …)
Thanks
Lior

Yep, I can reproduce this one.

Feb 04 23:24:49 volumiokitchen volumio[835]: info: ADDING THIS ITEM TO QUEUE: {"uri":"playlists/test","title":"test","service":"mpd"} Feb 04 23:24:49 volumiokitchen volumio[835]: info: Exploding uri playlists/test in service mpd Feb 04 23:24:49 volumiokitchen volumio[835]: info: ----------------------------/mnt/playlists/test Feb 04 23:24:49 volumiokitchen volumio[835]: fs.js:987 Feb 04 23:24:49 volumiokitchen volumio[835]: return binding.stat(pathModule._makeLong(path)); Feb 04 23:24:49 volumiokitchen volumio[835]: ^ Feb 04 23:24:49 volumiokitchen volumio[835]: Error: ENOENT: no such file or directory, stat '/mnt/playlists/test' Feb 04 23:24:49 volumiokitchen volumio[835]: at Error (native) Feb 04 23:24:49 volumiokitchen volumio[835]: at Object.fs.statSync (fs.js:987:18) Feb 04 23:24:49 volumiokitchen volumio[835]: at Object.statSync (/volumio/node_modules/graceful-fs/polyfills.js:289:22) Feb 04 23:24:49 volumiokitchen volumio[835]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2013:25) Feb 04 23:24:49 volumiokitchen volumio[835]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:1939:27) Feb 04 23:24:49 volumiokitchen volumio[835]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1088:22) Feb 04 23:24:49 volumiokitchen volumio[835]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:125:52) Feb 04 23:24:49 volumiokitchen volumio[835]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:136:24) Feb 04 23:24:49 volumiokitchen volumio[835]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:389:27) Feb 04 23:24:49 volumiokitchen volumio[835]: at Socket.<anonymous> (/volumio/app/plugins/user_interface/websocket/index.js:123:24) Feb 04 23:24:49 volumiokitchen mpd[564]: client: [0] closed Feb 04 23:24:49 volumiokitchen systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE

playlist contains few mp3 from USB DOK attached.
I also tried on local mp3 (placed in /data/INTERNAL) and done the “clean and play” on the playlist
got the same crash

eb 04 23:31:19 VolumioVM systemd-journal[188]: Suppressed 34935 messages from /system.slice/volumio.service
Feb 04 23:31:19 VolumioVM volumio[19223]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Feb 04 23:31:19 VolumioVM volumio[19223]: info: CURURI: playlists
Feb 04 23:31:19 VolumioVM volumio[19223]: info: [1486251079078] Listing playlists
Feb 04 23:31:23 VolumioVM volumio[19223]: info: CoreCommandRouter::volumioReplaceandPlayItems
Feb 04 23:31:23 VolumioVM volumio[19223]: info: [1486251083903] CoreStateMachine::ClearQueue
Feb 04 23:31:23 VolumioVM volumio[19223]: info: [1486251083903] CoreStateMachine::stop
Feb 04 23:31:23 VolumioVM volumio[19223]: info: [1486251083904] CoreStateMachine::setConsumeUpdateService undefined
Feb 04 23:31:23 VolumioVM volumio[19223]: info: [1486251083904] CorePlayQueue::clearPlayQueue
Feb 04 23:31:23 VolumioVM volumio[19223]: info: [1486251083904] CorePlayQueue::saveQueue
Feb 04 23:31:23 VolumioVM volumio[19223]: info: CoreCommandRouter::volumioPushQueue
Feb 04 23:31:23 VolumioVM volumio[19223]: info: [1486251083906] InterfaceMPD::pushQueue
Feb 04 23:31:23 VolumioVM volumio[19223]: info: [1486251083906] interfaceApi::pushQueue
Feb 04 23:31:23 VolumioVM volumio[19223]: info: [1486251083906] InterfaceWebUI::pushQueue
Feb 04 23:31:23 VolumioVM volumio[19223]: Missing error handler on socket.
Feb 04 23:31:23 VolumioVM volumio[19223]: TypeError: thisInterface.pushQueue is not a function
Feb 04 23:31:23 VolumioVM volumio[19223]: at /volumio/app/index.js:312:25
Feb 04 23:31:23 VolumioVM volumio[19223]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17)
Feb 04 23:31:23 VolumioVM volumio[19223]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12)
Feb 04 23:31:23 VolumioVM volumio[19223]: at CoreCommandRouter.volumioPushQueue (/volumio/app/index.js:310:11)
Feb 04 23:31:23 VolumioVM volumio[19223]: at CorePlayQueue.clearPlayQueue (/volumio/app/playqueue.js:200:28)
Feb 04 23:31:23 VolumioVM volumio[19223]: at CoreStateMachine.clearQueue (/volumio/app/statemachine.js:153:24)
Feb 04 23:31:23 VolumioVM volumio[19223]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:388:20)
Feb 04 23:31:23 VolumioVM volumio[19223]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:123:24)
Feb 04 23:31:23 VolumioVM volumio[19223]: at emitTwo (events.js:106:13)
Feb 04 23:31:23 VolumioVM volumio[19223]: at Socket.emit (events.js:191:7)

Hi,
Few days ago I tried to clear the playlist and I think I got the same bug as you !

playlist functionality seems broken even after factory reset … im trying to investigate but im not a JS programmer so it takes me some time …

sorry… it was my faulty plugin which caused the crash.
Turns out that I had to implement a pushQueue handler in order to receive notifications for actions done on other user interfaces …
Please disregard previous crash reports…

Nope allior, there is definitetly a problem here. It is reproducible on one of my devices