Spotify and webradio

dnj0104 · January 30, 2018, 9:34pm

Hello

I`m pretty new at this so now I need your help,

I have problem to play spotify and webradio @ RPI3, Suptronic X920 DAC and Volumio v2.348

The first day I started up my raspberry pi3 with suptronic dac x920 i had no problems at all, I install the spotify plugin, and it works
I add spotify songs and song from my plexmediaserver, I also played some radiochannels,

but the day after spotify stopt working, I can see the
directorys in webUi and android app same in webradio I can see the webradio directory but when I choose one, nothing happens, and it seems to that my raspberry pi locks for couple of second or is the app, same thing happens when i access webUI from computer, nothings happen.

But play files from my plexmediaserver works like a charm, one more thing, when I try to login from ssh into my pi I can be logged in for like 15 seconds
then the PI throw me out.

So wich logs do you need, ? and yes I have tried to boot my rpi3 several times.

chsims1 · January 31, 2018, 10:00am

If it’s reproducible behaviour you could ssh in and run ‘sudo journalctl -f’ before the problem occurs, and leave it running until after the problem has happened. Check the log for anything suspicious.

dnj0104 · January 31, 2018, 10:08am

Ok, thanks I will try, and beg for I can stay inside ssh without been kicked out.

chsims1 · January 31, 2018, 10:29am

It would be better if you could actually capture the problem occurring in the system logs as explained above, but failing this you could report the entire log volumio.github.io/docs/User_Man … oting.html AFTER the problem has occurred. Do a reboot before trying this.

dnj0104 · January 31, 2018, 10:45am

I try this tonight, maybe it not happens anymore cos after my first post i did a factory reset in volumio so I just have to put my Rpi back online again,
ps I have it connected to WIFI not cable.

dnj0104 · January 31, 2018, 8:40pm

okey now lets see at timestamp Jan 31 19:54:31 musikpaj volumio[1048]: info: No input data: I tried to fetch webradio and after that
I also try to play from spotify, the thing is now i can see tracks in my own favourite list, but as you can see the spotify api gives just errors back
I have checked my username and password for spotify and that is correct.

In this log i also played som music from my plexmediaserver, why the rpi looked for available networks in the middle of the log I dont know.
I can also tell that the rpi3 is very close to my router and has full strength in wifi.

Jan 31 19:54:31 musikpaj volumio[1048]: info: No input data
Jan 31 19:54:31 musikpaj volumio[1048]: info: No input data
Jan 31 19:54:31 musikpaj volumio[1048]: info: No input data
Jan 31 19:54:31 musikpaj volumio[1048]: info: No input data
Jan 31 19:54:31 musikpaj volumio[1048]: info: No input data
Jan 31 19:54:31 musikpaj volumio[1048]: info: No input data
Jan 31 19:54:31 musikpaj volumio[1048]: info: No input data
Jan 31 19:54:31 musikpaj volumio[1048]: info: No input data
Jan 31 19:54:31 musikpaj volumio[1048]: info: No input data
Jan 31 19:54:42 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Jan 31 19:54:46 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Jan 31 19:54:46 musikpaj volumio[1048]: info: Getting webradio selection
Jan 31 19:56:17 musikpaj volumio[1048]: info: CoreCommandRouter::volumioGetState
Jan 31 19:56:17 musikpaj volumio[1048]: info: [1517428577396] CoreStateMachine::getState
Jan 31 19:56:17 musikpaj volumio[1048]: info: [1517428577396] CorePlayQueue::getTrack 0
Jan 31 19:56:17 musikpaj volumio[1048]: info:
Jan 31 19:56:17 musikpaj volumio[1048]: [1517428577396] ---------------------------- Client requests Volumio state
Jan 31 19:56:17 musikpaj volumio[1048]: info: [1517428577398] InterfaceWebUI::pushState
Jan 31 19:56:17 musikpaj volumio[1048]: info: [1517428577400] ------------------------------ 5ms
Jan 31 19:56:19 musikpaj volumio[1048]: info:
Jan 31 19:56:19 musikpaj volumio[1048]: [1517428579218] ---------------------------- Client requests Menu Items
Jan 31 19:56:19 musikpaj volumio[1048]: info: [1517428579233] ------------------------------ 15ms
Jan 31 19:56:19 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jan 31 19:56:19 musikpaj volumio[1048]: Plugin multiroom or method getMultiroom not found
Jan 31 19:56:19 musikpaj volumio[1048]: info: [1517428579240] Listing playlists
Jan 31 19:56:19 musikpaj volumio[1048]: info: CoreCommandRouter::volumioGetBrowseSources
Jan 31 19:56:19 musikpaj volumio[1048]: info: CoreCommandRouter::volumioGetQueue
Jan 31 19:56:19 musikpaj volumio[1048]: info: [1517428579251] CoreStateMachine::getQueue
Jan 31 19:56:19 musikpaj volumio[1048]: info: [1517428579251] CorePlayQueue::getQueue
Jan 31 19:56:19 musikpaj volumio[1048]: info:
Jan 31 19:56:19 musikpaj volumio[1048]: [1517428579251] ---------------------------- Client requests Volumio queue
Jan 31 19:56:19 musikpaj volumio[1048]: info: [1517428579252] InterfaceWebUI::pushQueue
Jan 31 19:56:19 musikpaj volumio[1048]: info: [1517428579255] ------------------------------ 5ms
Jan 31 19:56:19 musikpaj volumio[1048]: info:
Jan 31 19:56:19 musikpaj volumio[1048]: [1517428579258] ---------------------------- Client requests Menu Items
Jan 31 19:56:19 musikpaj volumio[1048]: info:
Jan 31 19:56:19 musikpaj volumio[1048]: [1517428579261] ---------------------------- Client requests Menu Items
Jan 31 19:56:19 musikpaj volumio[1048]: info: [1517428579272] ------------------------------ 14ms
Jan 31 19:56:19 musikpaj volumio[1048]: info: [1517428579286] ------------------------------ 25ms
Jan 31 19:56:22 musikpaj volumio[1106]: 2018-01-31 19:56:22 [SPTF] 19:56:22.479 I [ap:1752] Connecting to AP ap.spotify.com:4070
Jan 31 19:56:27 musikpaj volumio[1106]: 2018-01-31 19:56:27 [SPTF] 19:56:27.485 E [ap:1694] AP Socket Error: Timeout reached (20000)
Jan 31 19:56:27 musikpaj volumio[1106]: 2018-01-31 19:56:27 [SPTF] 19:56:27.485 E [ap:3953] Connection error:  117
Jan 31 19:56:27 musikpaj volumio[1106]: 2018-01-31 19:56:27 [WARN] Connection error: Cannot connect to Spotify
Jan 31 19:56:45 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Jan 31 19:56:49 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Jan 31 19:57:24 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Jan 31 19:57:46 musikpaj volumio[1048]: (node:1048) UnhandledPromiseRejectionWarning: Unhandled promise rejection (rejection id: 8): Error
Jan 31 19:57:46 musikpaj volumio[1048]: (node:1048) UnhandledPromiseRejectionWarning: Unhandled promise rejection (rejection id: 9): Error
Jan 31 19:57:53 musikpaj volumio[1048]: info: CoreCommandRouter::volumioGetState
Jan 31 19:57:53 musikpaj volumio[1048]: info: [1517428673741] CoreStateMachine::getState
Jan 31 19:57:53 musikpaj volumio[1048]: info: [1517428673741] CorePlayQueue::getTrack 0
Jan 31 19:57:53 musikpaj volumio[1048]: info:
Jan 31 19:57:53 musikpaj volumio[1048]: [1517428673741] ---------------------------- Client requests Volumio state
Jan 31 19:57:53 musikpaj volumio[1048]: info: [1517428673742] InterfaceWebUI::pushState
Jan 31 19:57:53 musikpaj volumio[1048]: info: [1517428673744] ------------------------------ 4ms
Jan 31 19:57:55 musikpaj volumio[1048]: info:
Jan 31 19:57:55 musikpaj volumio[1048]: [1517428675504] ---------------------------- Client requests Menu Items
Jan 31 19:57:55 musikpaj volumio[1048]: info: [1517428675520] ------------------------------ 16ms
Jan 31 19:57:55 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jan 31 19:57:55 musikpaj volumio[1048]: Plugin multiroom or method getMultiroom not found
Jan 31 19:57:55 musikpaj volumio[1048]: info: [1517428675530] Listing playlists
Jan 31 19:57:55 musikpaj volumio[1048]: info: CoreCommandRouter::volumioGetBrowseSources
Jan 31 19:57:55 musikpaj volumio[1048]: info: CoreCommandRouter::volumioGetQueue
Jan 31 19:57:55 musikpaj volumio[1048]: info: [1517428675540] CoreStateMachine::getQueue
Jan 31 19:57:55 musikpaj volumio[1048]: info: [1517428675540] CorePlayQueue::getQueue
Jan 31 19:57:55 musikpaj volumio[1048]: info:
Jan 31 19:57:55 musikpaj volumio[1048]: [1517428675541] ---------------------------- Client requests Volumio queue
Jan 31 19:57:55 musikpaj volumio[1048]: info: [1517428675542] InterfaceWebUI::pushQueue
Jan 31 19:57:55 musikpaj volumio[1048]: info: [1517428675543] ------------------------------ 4ms
Jan 31 19:57:55 musikpaj volumio[1048]: info:
Jan 31 19:57:55 musikpaj volumio[1048]: [1517428675546] ---------------------------- Client requests Menu Items
Jan 31 19:57:55 musikpaj volumio[1048]: info:
Jan 31 19:57:55 musikpaj volumio[1048]: [1517428675549] ---------------------------- Client requests Menu Items
Jan 31 19:57:55 musikpaj volumio[1048]: info: [1517428675562] ------------------------------ 16ms
Jan 31 19:57:55 musikpaj volumio[1048]: info: [1517428675572] ------------------------------ 23ms
Jan 31 19:58:18 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jan 31 19:58:23 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jan 31 19:58:23 musikpaj volumio[1048]: info: No input data
Jan 31 19:58:26 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jan 31 19:58:26 musikpaj volumio[1048]: info: No input data
Jan 31 19:58:29 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jan 31 19:58:31 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jan 31 19:58:36 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jan 31 19:58:36 musikpaj volumio[1048]: info: No input data
Jan 31 19:58:41 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jan 31 19:58:46 musikpaj volumio[1048]: info: CoreCommandRouter::volumioAddQueueItems
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726174] CoreStateMachine::addQueueItems
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726175] CorePlayQueue::addQueueItems
Jan 31 19:58:46 musikpaj volumio[1048]: info: Adding Item to queue: upnp/http://192.168.8.105:32469/ContentDirectory/b6d69b83-876f-c05a-7c9b-57b0c7858485/control.xml@03a3a47084b5ca424c08
Jan 31 19:58:46 musikpaj volumio[1048]: info: Exploding uri upnp/http://192.168.8.105:32469/ContentDirectory/b6d69b83-876f-c05a-7c9b-57b0c7858485/control.xml@03a3a47084b5ca424c08 in service upnp_browser
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726244] CorePlayQueue::saveQueue
Jan 31 19:58:46 musikpaj volumio[1048]: info: CoreCommandRouter::volumioPushQueue
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726251] interfaceApi::pushQueue
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726252] InterfaceWebUI::pushQueue
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726256] CoreStateMachine::updateTrackBlock
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726258] CorePlayQueue::getTrackBlock
Jan 31 19:58:46 musikpaj volumio[1048]: info: CoreCommandRouter::volumioPlay
Jan 31 19:58:46 musikpaj volumio[1048]: UNSET VOLATILE
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726262] CoreStateMachine::play index 1
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726264] CoreStateMachine::setConsumeUpdateService undefined
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726267] CoreStateMachine::stop
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726267] CoreStateMachine::setConsumeUpdateService undefined
Jan 31 19:58:46 musikpaj volumio[1048]: UNSET VOLATILE
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726269] CoreStateMachine::play index undefined
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726270] CoreStateMachine::setConsumeUpdateService undefined
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726271] CorePlayQueue::getTrack 1
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726272] CoreStateMachine::startPlaybackTimer
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726273] CorePlayQueue::getTrack 1
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726275] ControllerUPNPBrowser::clearAddPlayTrack
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726277] ControllerMpd::sendMpdCommand stop
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726284] sending command...
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726294] parsing response...
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726296] ControllerMpd::sendMpdCommand clear
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726297] sending command...
Jan 31 19:58:46 musikpaj volumio[1048]: info:
Jan 31 19:58:46 musikpaj volumio[1048]: [1517428726301] ---------------------------- MPD announces system playlist update
Jan 31 19:58:46 musikpaj volumio[1048]: info: Ignoring MPD Status Update
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726305] parsing response...
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726306] ControllerMpd::sendMpdCommand load "http://192.168.8.105:32469/object/03a3a47084b5ca424c08/file.mp3"
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726309] sending command...
Jan 31 19:58:46 musikpaj volumio[1048]: info:
Jan 31 19:58:46 musikpaj volumio[1048]: info: [1517428726314] ------------------------------ 13ms
Jan 31 19:58:50 musikpaj volumio[1106]: 2018-01-31 19:58:50 [SPTF] 19:58:50.501 I [ap:1752] Connecting to AP ap.spotify.com:443
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734108] ControllerMpd::sendMpdCommand add "http://192.168.8.105:32469/object/03a3a47084b5ca424c08/file.mp3"
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734109] sending command...
Jan 31 19:58:54 musikpaj volumio[1048]: info:
Jan 31 19:58:54 musikpaj volumio[1048]: [1517428734113] ---------------------------- MPD announces system playlist update
Jan 31 19:58:54 musikpaj volumio[1048]: info: Ignoring MPD Status Update
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734115] parsing response...
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734116] CoreStateMachine::setConsumeUpdateService mpd
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734117] ControllerMpd::sendMpdCommand play
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734118] sending command...
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734130] ------------------------------ 17ms
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734131] parsing response...
Jan 31 19:58:54 musikpaj volumio[1048]: info:
Jan 31 19:58:54 musikpaj volumio[1048]: [1517428734245] ---------------------------- MPD announces state update: player
Jan 31 19:58:54 musikpaj kernel: pcm512x 1-004c: No SCLK, using BCLK: -2
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734249] ControllerMpd::getState
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734250] ControllerMpd::sendMpdCommand status
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734251] sending command...
Jan 31 19:58:54 musikpaj volumio[1048]: info:
Jan 31 19:58:54 musikpaj volumio[1048]: [1517428734253] ---------------------------- MPD announces state update: player
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734254] ControllerMpd::getState
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734255] ControllerMpd::sendMpdCommand status
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734256] parsing response...
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734258] sending command...
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734261] ControllerMpd::parseState
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734263] ControllerMpd::sendMpdCommand playlistinfo
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734264] sending command...
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734267] parsing response...
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734268] parsing response...
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734269] ControllerMpd::parseState
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734270] ControllerMpd::sendMpdCommand playlistinfo
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734272] ControllerMpd::parseTrackInfo
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734274] sending command...
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734276] ControllerMpd::pushState
Jan 31 19:58:54 musikpaj volumio[1048]: info: CoreCommandRouter::servicePushState
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734286] CoreStateMachine::syncState
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734287] CorePlayQueue::getTrack 1
Jan 31 19:58:54 musikpaj volumio[1048]: info: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":482,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.8.105:32469/object/03a3a47084b5ca424c08/file.mp3","trackType":"mp3"}
Jan 31 19:58:54 musikpaj volumio[1048]: info: CURRENT POSITION 1
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734289] CoreStateMachine::syncState   stateService play
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734290] CoreStateMachine::syncState   currentStatus stop
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734292] CoreStateMachine::pushState
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734293] CoreStateMachine::getState
Jan 31 19:58:54 musikpaj volumio[1048]: info: CoreCommandRouter::volumioPushState
Jan 31 19:58:54 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734304] interfaceApi::pushState
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734305] InterfaceWebUI::pushState
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734314] ------------------------------ 69ms
Jan 31 19:58:54 musikpaj volumio[1048]: info: Pushing Favourites {"service":"mpd","favourite":false}
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734322] parsing response...
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734323] ControllerMpd::parseTrackInfo
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734324] ControllerMpd::pushState
Jan 31 19:58:54 musikpaj volumio[1048]: info: CoreCommandRouter::servicePushState
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734326] CoreStateMachine::syncState
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734327] CorePlayQueue::getTrack 1
Jan 31 19:58:54 musikpaj volumio[1048]: info: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":482,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.8.105:32469/object/03a3a47084b5ca424c08/file.mp3","trackType":"mp3"}
Jan 31 19:58:54 musikpaj volumio[1048]: info: CURRENT POSITION 1
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734330] CoreStateMachine::syncState   stateService play
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734330] CoreStateMachine::syncState   currentStatus play
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734331] Received an update from plaugin. extracting info from payload
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734332] CoreStateMachine::pushState
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734333] CoreStateMachine::getState
Jan 31 19:58:54 musikpaj volumio[1048]: info: CoreCommandRouter::volumioPushState
Jan 31 19:58:54 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734336] interfaceApi::pushState
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734337] InterfaceWebUI::pushState
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734342] CoreStateMachine::pushState
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734343] CoreStateMachine::getState
Jan 31 19:58:54 musikpaj volumio[1048]: info: CoreCommandRouter::volumioPushState
Jan 31 19:58:54 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734346] interfaceApi::pushState
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734347] InterfaceWebUI::pushState
Jan 31 19:58:54 musikpaj volumio[1048]: info: [1517428734353] ------------------------------ 100ms
Jan 31 19:58:54 musikpaj volumio[1048]: info: Pushing Favourites {"service":"mpd","uri":"http://192.168.8.105:32469/object/03a3a47084b5ca424c08/file.mp3","favourite":false}
Jan 31 19:58:54 musikpaj volumio[1048]: info: Pushing Favourites {"service":"mpd","uri":"http://192.168.8.105:32469/object/03a3a47084b5ca424c08/file.mp3","favourite":false}
Jan 31 19:58:55 musikpaj volumio[1106]: 2018-01-31 19:58:55 [SPTF] 19:58:55.506 E [ap:1694] AP Socket Error: Timeout reached (20000)
Jan 31 19:58:55 musikpaj volumio[1106]: 2018-01-31 19:58:55 [SPTF] 19:58:55.506 E [ap:3953] Connection error:  117
Jan 31 19:58:55 musikpaj volumio[1106]: 2018-01-31 19:58:55 [WARN] Connection error: Cannot connect to Spotify
Jan 31 19:59:25 musikpaj volumio[1048]: info: CoreCommandRouter::volumioGetState
Jan 31 19:59:25 musikpaj volumio[1048]: info: [1517428765050] CoreStateMachine::getState
Jan 31 19:59:25 musikpaj volumio[1048]: info:
Jan 31 19:59:25 musikpaj volumio[1048]: [1517428765051] ---------------------------- Client requests Volumio state
Jan 31 19:59:25 musikpaj volumio[1048]: info: [1517428765052] InterfaceWebUI::pushState
Jan 31 19:59:25 musikpaj volumio[1048]: info: [1517428765055] ------------------------------ 6ms
Jan 31 19:59:25 musikpaj volumio[1048]: info:
Jan 31 19:59:25 musikpaj volumio[1048]: [1517428765077] ---------------------------- Client requests Menu Items
Jan 31 19:59:25 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jan 31 19:59:25 musikpaj volumio[1048]: Plugin multiroom or method getMultiroom not found
Jan 31 19:59:25 musikpaj volumio[1048]: info: [1517428765079] Listing playlists
Jan 31 19:59:25 musikpaj volumio[1048]: info: CoreCommandRouter::volumioGetBrowseSources
Jan 31 19:59:25 musikpaj volumio[1048]: info: CoreCommandRouter::volumioGetQueue
Jan 31 19:59:25 musikpaj volumio[1048]: info: [1517428765083] CoreStateMachine::getQueue
Jan 31 19:59:25 musikpaj volumio[1048]: info: [1517428765084] CorePlayQueue::getQueue
Jan 31 19:59:25 musikpaj volumio[1048]: info:
Jan 31 19:59:25 musikpaj volumio[1048]: [1517428765085] ---------------------------- Client requests Volumio queue
Jan 31 19:59:25 musikpaj volumio[1048]: info:
Jan 31 19:59:25 musikpaj volumio[1048]: [1517428765086] ---------------------------- Client requests Menu Items
Jan 31 19:59:25 musikpaj volumio[1048]: info:
Jan 31 19:59:25 musikpaj volumio[1048]: [1517428765087] ---------------------------- Client requests Menu Items
Jan 31 19:59:25 musikpaj volumio[1048]: info: [1517428765090] InterfaceWebUI::pushQueue
Jan 31 19:59:25 musikpaj volumio[1048]: info: [1517428765093] ------------------------------ 11ms
Jan 31 19:59:25 musikpaj volumio[1048]: info: [1517428765108] ------------------------------ 31ms
Jan 31 19:59:25 musikpaj volumio[1048]: info: [1517428765115] ------------------------------ 29ms
Jan 31 19:59:25 musikpaj volumio[1048]: info: [1517428765123] ------------------------------ 36ms
Jan 31 19:59:58 musikpaj volumio[1048]: info:
Jan 31 19:59:58 musikpaj volumio[1048]: [1517428798242] ---------------------------- Client requests Volumio pause
Jan 31 19:59:58 musikpaj volumio[1048]: info: CoreCommandRouter::volumioPause
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798245] CoreStateMachine::pause
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798247] CoreStateMachine::stPlaybackTimer
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798249] CoreStateMachine::servicePause
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798250] CorePlayQueue::getTrack 1
Jan 31 19:59:58 musikpaj volumio[1048]: info: CoreCommandRouter::servicePause
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798253] ControllerUPNPBrowser::pause
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798254] ControllerMpd::sendMpdCommand pause
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798255] sending command...
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798347] parsing response...
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798348] ------------------------------ 106ms
Jan 31 19:59:58 musikpaj volumio[1048]: info:
Jan 31 19:59:58 musikpaj volumio[1048]: [1517428798350] ---------------------------- MPD announces state update: player
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798351] ControllerMpd::getState
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798354] ControllerMpd::sendMpdCommand status
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798355] sending command...
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798357] parsing response...
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798358] ControllerMpd::parseState
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798359] ControllerMpd::sendMpdCommand playlistinfo
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798360] sending command...
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798363] parsing response...
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798363] ControllerMpd::parseTrackInfo
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798364] ControllerMpd::pushState
Jan 31 19:59:58 musikpaj volumio[1048]: info: CoreCommandRouter::servicePushState
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798366] CoreStateMachine::syncState
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798367] CorePlayQueue::getTrack 1
Jan 31 19:59:58 musikpaj volumio[1048]: info: STATE SERVICE {"status":"pause","position":0,"seek":64481,"duration":482,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.8.105:32469/object/03a3a47084b5ca424c08/file.mp3","trackType":"mp3"}
Jan 31 19:59:58 musikpaj volumio[1048]: info: CURRENT POSITION 1
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798370] CoreStateMachine::syncState   stateService pause
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798371] CoreStateMachine::syncState   currentStatus pause
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798372] CoreStateMachine::pushState
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798373] CoreStateMachine::getState
Jan 31 19:59:58 musikpaj volumio[1048]: info: CoreCommandRouter::volumioPushState
Jan 31 19:59:58 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798377] interfaceApi::pushState
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798378] InterfaceWebUI::pushState
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798382] CoreStateMachine::stPlaybackTimer
Jan 31 19:59:58 musikpaj volumio[1048]: info: [1517428798386] ------------------------------ 36ms
Jan 31 19:59:58 musikpaj volumio[1048]: info: Pushing Favourites {"service":"mpd","uri":"http://192.168.8.105:32469/object/03a3a47084b5ca424c08/file.mp3","favourite":false}
Jan 31 20:00:04 musikpaj volumio[1048]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 31 20:00:04 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Jan 31 20:00:04 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 31 20:00:04 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jan 31 20:00:04 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 31 20:00:04 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 31 20:00:04 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 31 20:00:04 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 31 20:00:04 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 31 20:00:04 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 31 20:00:04 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 31 20:00:24 musikpaj volumio[1048]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 31 20:00:24 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jan 31 20:00:24 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jan 31 20:00:24 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jan 31 20:00:24 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 31 20:00:24 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 31 20:00:24 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 31 20:00:24 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 31 20:00:25 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jan 31 20:00:25 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Jan 31 20:00:29 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jan 31 20:00:33 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jan 31 20:00:37 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jan 31 20:00:41 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jan 31 20:00:45 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jan 31 20:00:49 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jan 31 20:00:53 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jan 31 20:00:54 musikpaj volumio[1048]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 31 20:00:55 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork
Jan 31 20:00:55 musikpaj sudo[7429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0
Jan 31 20:00:55 musikpaj sudo[7429]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 31 20:00:55 musikpaj sudo[7429]: pam_unix(sudo:session): session closed for user root
Jan 31 20:00:55 musikpaj sudo[7442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Jan 31 20:00:55 musikpaj sudo[7442]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 31 20:00:55 musikpaj sudo[7442]: pam_unix(sudo:session): session closed for user root
Jan 31 20:00:55 musikpaj sudo[7456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Jan 31 20:00:55 musikpaj sudo[7456]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 31 20:00:55 musikpaj sudo[7456]: pam_unix(sudo:session): session closed for user root
Jan 31 20:00:55 musikpaj sudo[7467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Jan 31 20:00:55 musikpaj sudo[7467]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 31 20:00:55 musikpaj sudo[7467]: pam_unix(sudo:session): session closed for user root
Jan 31 20:00:56 musikpaj sudo[7480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jan 31 20:00:56 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jan 31 20:00:56 musikpaj sudo[7480]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 31 20:00:56 musikpaj sudo[7483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jan 31 20:00:56 musikpaj sudo[7480]: pam_unix(sudo:session): session closed for user root
Jan 31 20:00:56 musikpaj sudo[7483]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 31 20:00:56 musikpaj sudo[7483]: pam_unix(sudo:session): session closed for user root
Jan 31 20:00:56 musikpaj sudo[7499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jan 31 20:00:56 musikpaj sudo[7499]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 31 20:00:56 musikpaj wpa_supplicant[988]: nl80211: Drv Event 33 (NL80211_CMD_TRIGGER_SCAN) received for wlan0
Jan 31 20:00:56 musikpaj wpa_supplicant[988]: wlan0: nl80211: Scan trigger
Jan 31 20:00:56 musikpaj wpa_supplicant[988]: wlan0: Event SCAN_STARTED (49) received
Jan 31 20:00:56 musikpaj wpa_supplicant[988]: wlan0: External program started a scan
Jan 31 20:00:57 musikpaj wpa_supplicant[988]: nl80211: Drv Event 34 (NL80211_CMD_NEW_SCAN_RESULTS) received for wlan0
Jan 31 20:00:57 musikpaj wpa_supplicant[988]: wlan0: nl80211: New scan results available
Jan 31 20:00:57 musikpaj wpa_supplicant[988]: nl80211: Scan probed for SSID ''
Jan 31 20:00:57 musikpaj wpa_supplicant[988]: nl80211: Scan included frequencies: 2412 2417 2422 2427 2432 2437 2442 2447 2452 2457 2462
Jan 31 20:00:57 musikpaj wpa_supplicant[988]: wlan0: Event SCAN_RESULTS (3) received
Jan 31 20:00:57 musikpaj wpa_supplicant[988]: wlan0: Scan completed in 0.714612 seconds
Jan 31 20:00:57 musikpaj wpa_supplicant[988]: nl80211: Associated on 2462 MHz
Jan 31 20:00:57 musikpaj wpa_supplicant[988]: nl80211: Associated with 44:6e:e5:3b:45:dd
Jan 31 20:00:57 musikpaj wpa_supplicant[988]: nl80211: Received scan results (6 BSSes)
Jan 31 20:00:57 musikpaj wpa_supplicant[988]: nl80211: Scan results indicate BSS status with 44:6e:e5:3b:45:dd as associated
Jan 31 20:00:57 musikpaj wpa_supplicant[988]: wlan0: BSS: Start scan result update 2
Jan 31 20:00:57 musikpaj wpa_supplicant[988]: wlan0: BSS: Add new id 5 BSSID b0:e1:7e:f3:46:ce SSID '3Bredband-2.4G-46CE'
Jan 31 20:00:57 musikpaj wpa_supplicant[988]: wlan0: BSS: Add new id 6 BSSID 8c:25:05:e0:a9:69 SSID '3MobilWiFi-4G-A969'
Jan 31 20:00:57 musikpaj wpa_supplicant[988]: wlan0: BSS: Add new id 7 BSSID 54:e6:fc:be:83:ca SSID 'Lottas'
Jan 31 20:00:57 musikpaj wpa_supplicant[988]: wlan0: BSS: Add new id 8 BSSID 00:25:92:ef:9c:90 SSID 'AWF320_9C90'
Jan 31 20:00:57 musikpaj wpa_supplicant[988]: wlan0: BSS: Add new id 9 BSSID 9c:97:26:74:ac:db SSID 'TeliaGateway9C-97-26-74-AC-DB'
Jan 31 20:00:57 musikpaj wpa_supplicant[988]: BSS: last_scan_res_used=6/32
Jan 31 20:00:57 musikpaj wpa_supplicant[988]: wlan0: New scan results available (own=0 ext=1)
Jan 31 20:00:57 musikpaj wpa_supplicant[988]: wlan0: Do not use results from externally requested scan operation for network selection
Jan 31 20:00:57 musikpaj wpa_supplicant[988]: RTM_NEWLINK: ifi_index=3 ifname=wlan0 wext ifi_family=0 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
Jan 31 20:00:57 musikpaj sudo[7499]: pam_unix(sudo:session): session closed for user root
Jan 31 20:00:58 musikpaj volumio[1048]: info: CoreCommandRouter::volumioGetState
Jan 31 20:00:58 musikpaj volumio[1048]: info: [1517428858960] CoreStateMachine::getState
Jan 31 20:00:58 musikpaj volumio[1048]: info:
Jan 31 20:00:58 musikpaj volumio[1048]: [1517428858961] ---------------------------- Client requests Volumio state
Jan 31 20:00:58 musikpaj volumio[1048]: info: [1517428858963] InterfaceWebUI::pushState
Jan 31 20:00:58 musikpaj volumio[1048]: info: [1517428858967] ------------------------------ 8ms
Jan 31 20:01:04 musikpaj volumio[1048]: info:
Jan 31 20:01:04 musikpaj volumio[1048]: [1517428864635] ---------------------------- Client requests Menu Items
Jan 31 20:01:04 musikpaj volumio[1048]: info: [1517428864647] ------------------------------ 12ms
Jan 31 20:01:04 musikpaj volumio[1048]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jan 31 20:01:04 musikpaj volumio[1048]: Plugin multiroom or method getMultiroom not found
Jan 31 20:01:04 musikpaj volumio[1048]: info: [1517428864657] Listing playlists
Jan 31 20:01:04 musikpaj volumio[1048]: info: CoreCommandRouter::volumioGetBrowseSources
Jan 31 20:01:04 musikpaj volumio[1048]: info: CoreCommandRouter::volumioGetQueue
Jan 31 20:01:04 musikpaj volumio[1048]: info: [1517428864670] CoreStateMachine::getQueue
Jan 31 20:01:04 musikpaj volumio[1048]: info: [1517428864671] CorePlayQueue::getQueue
Jan 31 20:01:04 musikpaj volumio[1048]: info:
Jan 31 20:01:04 musikpaj volumio[1048]: [1517428864671] ---------------------------- Client requests Volumio queue
Jan 31 20:01:04 musikpaj volumio[1048]: info: [1517428864672] InterfaceWebUI::pushQueue
Jan 31 20:01:04 musikpaj volumio[1048]: info: [1517428864674] ------------------------------ 4ms
Jan 31 20:01:04 musikpaj volumio[1048]: info:
Jan 31 20:01:04 musikpaj volumio[1048]: [1517428864675] ---------------------------- Client requests Menu Items
Jan 31 20:01:04 musikpaj volumio[1048]: info:
Jan 31 20:01:04 musikpaj volumio[1048]: [1517428864678] ---------------------------- Client requests Menu Items
Jan 31 20:01:04 musikpaj volumio[1048]: info: [1517428864687] ------------------------------ 13ms
Jan 31 20:01:04 musikpaj volumio[1048]: info: [1517428864701] ------------------------------ 23ms
Jan 31 20:01:18 musikpaj volumio[1106]: 2018-01-31 20:01:18 [SPTF] 20:01:18.027 I [ap:1752] Connecting to AP ap.spotify.com:80
Jan 31 20:01:23 musikpaj volumio[1106]: 2018-01-31 20:01:23 [SPTF] 20:01:23.032 E [ap:1694] AP Socket Error: Timeout reached (20000)
Jan 31 20:01:23 musikpaj volumio[1106]: 2018-01-31 20:01:23 [SPTF] 20:01:23.032 E [ap:3953] Connection error:  117
Jan 31 20:01:23 musikpaj volumio[1106]: 2018-01-31 20:01:23 [WARN] Connection error: Cannot connect to Spotify
Jan 31 20:01:31 musikpaj volumio[1048]: info:
Jan 31 20:01:31 musikpaj volumio[1048]: [1517428891545] ---------------------------- Client requests Volumio play
Jan 31 20:01:31 musikpaj volumio[1048]: info: CoreCommandRouter::volumioPlay
Jan 31 20:01:31 musikpaj volumio[1048]: UNSET VOLATILE
Jan 31 20:01:31 musikpaj volumio[1048]: info: [1517428891547] CoreStateMachine::play index undefined
Jan 31 20:01:31 musikpaj volumio[1048]: info: [1517428891547] CoreStateMachine::setConsumeUpdateService undefined
Jan 31 20:01:31 musikpaj volumio[1048]: info: [1517428891548] CorePlayQueue::getTrack 1
Jan 31 20:01:31 musikpaj volumio[1048]: info: [1517428891548] CoreStateMachine::startPlaybackTimer
Jan 31 20:01:31 musikpaj volumio[1048]: info: [1517428891549] CorePlayQueue::getTrack 1
Jan 31 20:01:31 musikpaj volumio[1048]: info: [1517428891549] ControllerUPNPBrowser::resume
Jan 31 20:01:31 musikpaj volumio[1048]: info: [1517428891550] ControllerMpd::sendMpdCommand play
Jan 31 20:01:31 musikpaj volumio[1048]: info: [1517428891559] sending command...
Jan 31 20:01:31 musikpaj volumio[1048]: info: [1517428891561] ------------------------------ 16ms
Jan 31 20:01:31 musikpaj kernel: pcm512x 1-004c: No SCLK, using BCLK: -2
Jan 31 20:01:31 musikpaj volumio[1048]: info:

dnj0104 · January 31, 2018, 9:13pm

And now in this log I moved my RPI to its actual place it has now 2 bars in wifi strength ( yes I stopped the music pressed shut down and waited for the green light to stop blin before i pulled the plug)

as you can see here in this log it has differnt erros than the first log, and I just did same thing, tried to play webradio and tried with spotify and played
a song from my plexmediaserver and thats worked as before. an extra thing I did was to disable the built in hotspot in volumio.

-- Logs begin at Wed 2018-01-31 20:47:17 UTC. --
Jan 31 20:48:52 musikpaj systemd[1524]: Reached target Basic System.
Jan 31 20:48:52 musikpaj systemd[1524]: Starting Default.
Jan 31 20:48:52 musikpaj systemd[1524]: Reached target Default.
Jan 31 20:48:52 musikpaj systemd[1524]: Startup finished in 43ms.
Jan 31 20:48:52 musikpaj systemd[1]: Started User Manager for UID 1000.
Jan 31 20:48:52 musikpaj sshd[1213]: lastlog_openseek: Couldn't stat /var/log/la                                                                                                                               stlog: No such file or directory
Jan 31 20:48:52 musikpaj sshd[1213]: lastlog_openseek: Couldn't stat /var/log/la                                                                                                                               stlog: No such file or directory
Jan 31 20:49:24 musikpaj ntpd_intres[725]: host name not found: 1.debian.pool.nt                                                                                                                               p.org
Jan 31 20:49:32 musikpaj sudo[1804]: volumio : TTY=pts/0 ; PWD=/home/volumio ; U                                                                                                                               SER=root ; COMMAND=/bin/journalctl -f
Jan 31 20:49:32 musikpaj sudo[1804]: pam_unix(sudo:session): session opened for                                                                                                                                user root by volumio(uid=0)
Jan 31 20:49:35 musikpaj volumio[923]: info: CoreCommandRouter::volumioGetState
Jan 31 20:49:35 musikpaj volumio[923]: info: [1517431775730] CoreStateMachine::g                                                                                                                               etState
Jan 31 20:49:35 musikpaj volumio[923]: info: [1517431775730] CorePlayQueue::getT                                                                                                                               rack 0
Jan 31 20:49:35 musikpaj volumio[923]: info:
Jan 31 20:49:35 musikpaj volumio[923]: [1517431775731] -------------------------                                                                                                                               --- Client requests Volumio state
Jan 31 20:49:35 musikpaj volumio[923]: info: [1517431775734] InterfaceWebUI::pus                                                                                                                               hState
Jan 31 20:49:35 musikpaj volumio[923]: info: [1517431775753] -------------------                                                                                                                               ----------- 24ms
Jan 31 20:49:35 musikpaj volumio[923]: info:
Jan 31 20:49:35 musikpaj volumio[923]: [1517431775759] -------------------------                                                                                                                               --- Client requests Menu Items
Jan 31 20:49:35 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin:                                                                                                                                multiroom , getMultiroom
Jan 31 20:49:35 musikpaj volumio[923]: Plugin multiroom or method getMultiroom n                                                                                                                               ot found
Jan 31 20:49:35 musikpaj volumio[923]: info: [1517431775776] Listing playlists
Jan 31 20:49:35 musikpaj volumio[923]: info: CoreCommandRouter::volumioGetBrowse                                                                                                                               Sources
Jan 31 20:49:35 musikpaj volumio[923]: info: CoreCommandRouter::volumioGetQueue
Jan 31 20:49:35 musikpaj volumio[923]: info: [1517431775781] CoreStateMachine::g                                                                                                                               etQueue
Jan 31 20:49:35 musikpaj volumio[923]: info: [1517431775782] CorePlayQueue::getQ                                                                                                                               ueue
Jan 31 20:49:35 musikpaj volumio[923]: info:
Jan 31 20:49:35 musikpaj volumio[923]: [1517431775783] -------------------------                                                                                                                               --- Client requests Volumio queue
Jan 31 20:49:35 musikpaj volumio[923]: info:
Jan 31 20:49:35 musikpaj volumio[923]: [1517431775785] -------------------------                                                                                                                               --- Client requests Menu Items
Jan 31 20:49:35 musikpaj volumio[923]: info:
Jan 31 20:49:35 musikpaj volumio[923]: [1517431775786] -------------------------                                                                                                                               --- Client requests Menu Items
Jan 31 20:49:35 musikpaj volumio[923]: info: [1517431775791] InterfaceWebUI::pus                                                                                                                               hQueue
Jan 31 20:49:35 musikpaj volumio[923]: info: [1517431775794] -------------------                                                                                                                               ----------- 14ms
Jan 31 20:49:35 musikpaj volumio[923]: info: [1517431775866] -------------------                                                                                                                               ----------- 107ms
Jan 31 20:49:35 musikpaj volumio[923]: info: [1517431775877] -------------------                                                                                                                               ----------- 92ms
Jan 31 20:49:35 musikpaj volumio[923]: info: [1517431775885] -------------------                                                                                                                               ----------- 99ms
Jan 31 20:49:52 musikpaj volumio[923]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.348&uuid=db34e0a9ef7350fae6bc66c7bfac3999" http://updates.volumio.org:7070/downloader-v1/track-device
Jan 31 20:49:52 musikpaj volumio[923]: % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
Jan 31 20:49:52 musikpaj volumio[923]: Dload  Upload   Total   Spent    Left  Speed
Jan 31 20:49:52 musikpaj volumio[923]: [1.9K blob data]
Jan 31 20:49:52 musikpaj volumio[923]: [1.9K blob data]
Jan 31 20:49:52 musikpaj volumio[923]: [460B blob data]
Jan 31 20:49:52 musikpaj volumio[923]: retrying in 5 seconds, trial 1
Jan 31 20:49:52 musikpaj volumio[923]: Volumio Calling Home
Jan 31 20:50:20 musikpaj ntpd_intres[725]: host name not found: 2.debian.pool.ntp.org
Jan 31 20:50:37 musikpaj wpa_supplicant[858]: wlan0: BSS: Remove id 0 BSSID b0:e1:7e:f3:46:ce SSID '3Bredband-2.4G-46CE' due to wpa_bss_flush_by_age
Jan 31 20:50:37 musikpaj wpa_supplicant[858]: wlan0: BSS: Remove id 2 BSSID 0c:51:01:e4:79:d0 SSID 'Urbans Wi-Fi' due to wpa_bss_flush_by_age
Jan 31 20:50:37 musikpaj wpa_supplicant[858]: wlan0: BSS: Remove id 3 BSSID 64:a5:c3:68:fb:68 SSID 'Urbans Wi-Fi' due to wpa_bss_flush_by_age
Jan 31 20:50:37 musikpaj wpa_supplicant[858]: wlan0: BSS: Remove id 4 BSSID 0c:96:bf:4d:1c:b1 SSID 'Not' due to wpa_bss_flush_by_age
Jan 31 20:50:38 musikpaj volumio[923]: info: CoreCommandRouter::volumioGetState
Jan 31 20:50:38 musikpaj volumio[923]: info: [1517431838077] CoreStateMachine::getState
Jan 31 20:50:38 musikpaj volumio[923]: info: [1517431838078] CorePlayQueue::getTrack 0
Jan 31 20:50:38 musikpaj volumio[923]: info:
Jan 31 20:50:38 musikpaj volumio[923]: [1517431838079] ---------------------------- Client requests Volumio state
Jan 31 20:50:38 musikpaj volumio[923]: info: [1517431838080] InterfaceWebUI::pushState
Jan 31 20:50:38 musikpaj volumio[923]: info: [1517431838099] ------------------------------ 22ms
Jan 31 20:50:38 musikpaj volumio[923]: info:
Jan 31 20:50:38 musikpaj volumio[923]: [1517431838133] ---------------------------- Client requests Menu Items
Jan 31 20:50:38 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jan 31 20:50:38 musikpaj volumio[923]: info: [1517431838149] ------------------------------ 16ms
Jan 31 20:50:38 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Jan 31 20:50:38 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Jan 31 20:50:38 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jan 31 20:50:38 musikpaj volumio[923]: Plugin multiroom or method getMultiroom not found
Jan 31 20:50:38 musikpaj volumio[923]: info: [1517431838208] Listing playlists
Jan 31 20:50:47 musikpaj volumio[923]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 31 20:50:48 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork
Jan 31 20:50:48 musikpaj sudo[2350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0
Jan 31 20:50:48 musikpaj sudo[2350]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 31 20:50:48 musikpaj sudo[2350]: pam_unix(sudo:session): session closed for user root
Jan 31 20:50:48 musikpaj sudo[2363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Jan 31 20:50:48 musikpaj sudo[2363]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 31 20:50:48 musikpaj sudo[2363]: pam_unix(sudo:session): session closed for user root
Jan 31 20:50:48 musikpaj sudo[2377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Jan 31 20:50:48 musikpaj sudo[2377]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 31 20:50:48 musikpaj sudo[2377]: pam_unix(sudo:session): session closed for user root
Jan 31 20:50:48 musikpaj sudo[2388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Jan 31 20:50:48 musikpaj sudo[2388]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 31 20:50:48 musikpaj sudo[2388]: pam_unix(sudo:session): session closed for user root
Jan 31 20:50:49 musikpaj sudo[2401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jan 31 20:50:49 musikpaj sudo[2401]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 31 20:50:49 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jan 31 20:50:49 musikpaj sudo[2403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jan 31 20:50:49 musikpaj sudo[2401]: pam_unix(sudo:session): session closed for user root
Jan 31 20:50:49 musikpaj sudo[2403]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 31 20:50:49 musikpaj sudo[2403]: pam_unix(sudo:session): session closed for user root
Jan 31 20:50:49 musikpaj sudo[2423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jan 31 20:50:49 musikpaj sudo[2423]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 31 20:50:49 musikpaj wpa_supplicant[858]: nl80211: Drv Event 33 (NL80211_CMD_TRIGGER_SCAN) received for wlan0
Jan 31 20:50:49 musikpaj wpa_supplicant[858]: wlan0: nl80211: Scan trigger
Jan 31 20:50:49 musikpaj wpa_supplicant[858]: wlan0: Event SCAN_STARTED (49) received
Jan 31 20:50:49 musikpaj wpa_supplicant[858]: wlan0: External program started a scan
Jan 31 20:50:50 musikpaj wpa_supplicant[858]: RTM_NEWLINK: ifi_index=3 ifname=wlan0 wext ifi_family=0 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
Jan 31 20:50:50 musikpaj wpa_supplicant[858]: nl80211: Drv Event 34 (NL80211_CMD_NEW_SCAN_RESULTS) received for wlan0
Jan 31 20:50:50 musikpaj wpa_supplicant[858]: wlan0: nl80211: New scan results available
Jan 31 20:50:50 musikpaj wpa_supplicant[858]: nl80211: Scan probed for SSID ''
Jan 31 20:50:50 musikpaj wpa_supplicant[858]: nl80211: Scan included frequencies: 2412 2417 2422 2427 2432 2437 2442 2447 2452 2457 2462
Jan 31 20:50:50 musikpaj wpa_supplicant[858]: wlan0: Event SCAN_RESULTS (3) received
Jan 31 20:50:50 musikpaj wpa_supplicant[858]: wlan0: Scan completed in 0.717842 seconds
Jan 31 20:50:50 musikpaj wpa_supplicant[858]: nl80211: Associated on 2462 MHz
Jan 31 20:50:50 musikpaj wpa_supplicant[858]: nl80211: Associated with 44:6e:e5:3b:45:dd
Jan 31 20:50:50 musikpaj wpa_supplicant[858]: nl80211: Received scan results (4 BSSes)
Jan 31 20:50:50 musikpaj wpa_supplicant[858]: nl80211: Scan results indicate BSS status with 44:6e:e5:3b:45:dd as associated
Jan 31 20:50:50 musikpaj wpa_supplicant[858]: wlan0: BSS: Start scan result update 2
Jan 31 20:50:50 musikpaj wpa_supplicant[858]: wlan0: BSS: Add new id 5 BSSID b0:e1:7e:f3:46:ce SSID '3Bredband-2.4G-46CE'
Jan 31 20:50:50 musikpaj wpa_supplicant[858]: wlan0: BSS: Add new id 6 BSSID 0c:96:bf:4d:1c:b1 SSID 'Not'
Jan 31 20:50:50 musikpaj wpa_supplicant[858]: wlan0: BSS: Add new id 7 BSSID 0c:51:01:e4:79:d0 SSID 'Urbans Wi-Fi'
Jan 31 20:50:50 musikpaj wpa_supplicant[858]: BSS: last_scan_res_used=4/32
Jan 31 20:50:50 musikpaj wpa_supplicant[858]: wlan0: New scan results available (own=0 ext=1)
Jan 31 20:50:50 musikpaj wpa_supplicant[858]: wlan0: Do not use results from externally requested scan operation for network selection
Jan 31 20:50:50 musikpaj sudo[2423]: pam_unix(sudo:session): session closed for user root
Jan 31 20:50:59 musikpaj volumio[923]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.348&uuid=db34e0a9ef7350fae6bc66c7bfac3999" http://updates.volumio.org:7070/downloader-v1/track-device
Jan 31 20:50:59 musikpaj volumio[923]: % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
Jan 31 20:50:59 musikpaj volumio[923]: Dload  Upload   Total   Spent    Left  Speed
Jan 31 20:50:59 musikpaj volumio[923]: [1.9K blob data]
Jan 31 20:50:59 musikpaj volumio[923]: [1.9K blob data]
Jan 31 20:50:59 musikpaj volumio[923]: [381B blob data]
Jan 31 20:50:59 musikpaj volumio[923]: retrying in 5 seconds, trial 2
Jan 31 20:50:59 musikpaj volumio[923]: Volumio Calling Home
Jan 31 20:51:16 musikpaj ntpd_intres[725]: host name not found: 3.debian.pool.ntp.org
Jan 31 20:51:36 musikpaj volumio[923]: info: CoreCommandRouter::volumioGetState
Jan 31 20:51:36 musikpaj volumio[923]: info: [1517431896109] CoreStateMachine::getState
Jan 31 20:51:36 musikpaj volumio[923]: info: [1517431896110] CorePlayQueue::getTrack 0
Jan 31 20:51:36 musikpaj volumio[923]: info:
Jan 31 20:51:36 musikpaj volumio[923]: [1517431896110] ---------------------------- Client requests Volumio state
Jan 31 20:51:36 musikpaj volumio[923]: info: [1517431896111] InterfaceWebUI::pushState
Jan 31 20:51:36 musikpaj volumio[923]: info: [1517431896128] ------------------------------ 20ms
Jan 31 20:51:38 musikpaj volumio[923]: info: CoreCommandRouter::volumioGetBrowseSources
Jan 31 20:51:38 musikpaj volumio[923]: info: CoreCommandRouter::volumioGetQueue
Jan 31 20:51:38 musikpaj volumio[923]: info: [1517431898930] CoreStateMachine::getQueue
Jan 31 20:51:38 musikpaj volumio[923]: info: [1517431898931] CorePlayQueue::getQueue
Jan 31 20:51:38 musikpaj volumio[923]: info:
Jan 31 20:51:38 musikpaj volumio[923]: [1517431898931] ---------------------------- Client requests Volumio queue
Jan 31 20:51:38 musikpaj volumio[923]: info: [1517431898932] InterfaceWebUI::pushQueue
Jan 31 20:51:38 musikpaj volumio[923]: info: [1517431898934] ------------------------------ 4ms
Jan 31 20:51:45 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Jan 31 20:51:45 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 31 20:51:51 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Jan 31 20:51:53 musikpaj volumio[923]: info:
Jan 31 20:51:53 musikpaj volumio[923]: [1517431913787] ---------------------------- Client requests Menu Items
Jan 31 20:51:53 musikpaj volumio[923]: info: [1517431913830] ------------------------------ 43ms
Jan 31 20:51:53 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jan 31 20:51:53 musikpaj volumio[923]: Plugin multiroom or method getMultiroom not found
Jan 31 20:51:53 musikpaj volumio[923]: info: [1517431913840] Listing playlists
Jan 31 20:51:53 musikpaj volumio[923]: info: CoreCommandRouter::volumioGetBrowseSources
Jan 31 20:51:53 musikpaj volumio[923]: info: CoreCommandRouter::volumioGetQueue
Jan 31 20:51:53 musikpaj volumio[923]: info: [1517431913852] CoreStateMachine::getQueue
Jan 31 20:51:53 musikpaj volumio[923]: info: [1517431913853] CorePlayQueue::getQueue
Jan 31 20:51:53 musikpaj volumio[923]: info:
Jan 31 20:51:53 musikpaj volumio[923]: [1517431913853] ---------------------------- Client requests Volumio queue
Jan 31 20:51:53 musikpaj volumio[923]: info: [1517431913854] InterfaceWebUI::pushQueue
Jan 31 20:51:53 musikpaj volumio[923]: info: [1517431913856] ------------------------------ 5ms
Jan 31 20:51:53 musikpaj volumio[923]: info:
Jan 31 20:51:53 musikpaj volumio[923]: [1517431913860] ---------------------------- Client requests Menu Items
Jan 31 20:51:53 musikpaj volumio[923]: info:
Jan 31 20:51:53 musikpaj volumio[923]: [1517431913864] ---------------------------- Client requests Menu Items
Jan 31 20:51:53 musikpaj volumio[923]: info: [1517431913889] ------------------------------ 29ms
Jan 31 20:51:53 musikpaj volumio[923]: info: [1517431913901] ------------------------------ 37ms
Jan 31 20:52:44 musikpaj volumio[923]: info: CoreCommandRouter::volumioGetState
Jan 31 20:52:44 musikpaj volumio[923]: info: [1517431964145] CoreStateMachine::g                                                                                                                               etState
Jan 31 20:52:44 musikpaj volumio[923]: info: [1517431964145] CorePlayQueue::getT                                                                                                                               rack 0
Jan 31 20:52:44 musikpaj volumio[923]: info:
Jan 31 20:52:44 musikpaj volumio[923]: [1517431964146] -------------------------                                                                                                                               --- Client requests Volumio state
Jan 31 20:52:44 musikpaj volumio[923]: info: [1517431964147] InterfaceWebUI::pus                                                                                                                               hState
Jan 31 20:52:44 musikpaj volumio[923]: info: [1517431964174] -------------------                                                                                                                               ----------- 30ms
Jan 31 20:52:44 musikpaj volumio[923]: info:
Jan 31 20:52:44 musikpaj volumio[923]: [1517431964186] -------------------------                                                                                                                               --- Client requests Menu Items
Jan 31 20:52:44 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin:                                                                                                                                appearance , getUiSettings
Jan 31 20:52:44 musikpaj volumio[923]: info: [1517431964209] -------------------                                                                                                                               ----------- 23ms
Jan 31 20:52:44 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin:                                                                                                                                wizard , getShowWizard
Jan 31 20:52:44 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin:                                                                                                                                system , getShowWizard
Jan 31 20:52:44 musikpaj volumio[923]: info: No input data
Jan 31 20:52:44 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin:                                                                                                                                multiroom , getMultiroom
Jan 31 20:52:44 musikpaj volumio[923]: Plugin multiroom or method getMultiroom n                                                                                                                               ot found
Jan 31 20:52:44 musikpaj volumio[923]: info: [1517431964284] Listing playlists
Jan 31 20:52:47 musikpaj volumio[923]: (node:923) UnhandledPromiseRejectionWarni                                                                                                                               ng: Unhandled promise rejection (rejection id: 1): Error
Jan 31 20:53:02 musikpaj volumio[923]: info: CoreCommandRouter::volumioGetBrowseSources
Jan 31 20:53:02 musikpaj volumio[923]: info: CoreCommandRouter::volumioGetQueue
Jan 31 20:53:02 musikpaj volumio[923]: info: [1517431982248] CoreStateMachine::getQueue
Jan 31 20:53:02 musikpaj volumio[923]: info: [1517431982249] CorePlayQueue::getQueue
Jan 31 20:53:02 musikpaj volumio[923]: info:
Jan 31 20:53:02 musikpaj volumio[923]: [1517431982251] ---------------------------- Client requests Volumio queue
Jan 31 20:53:02 musikpaj volumio[923]: info: [1517431982254] InterfaceWebUI::pushQueue
Jan 31 20:53:02 musikpaj volumio[923]: info: [1517431982257] ------------------------------ 12ms
Jan 31 20:53:02 musikpaj volumio[923]: info: No input data
Jan 31 20:53:02 musikpaj volumio[923]: info: No input data
Jan 31 20:53:02 musikpaj volumio[923]: info: No input data
Jan 31 20:53:02 musikpaj volumio[923]: info: No input data
Jan 31 20:53:02 musikpaj volumio[923]: info: No input data
Jan 31 20:53:02 musikpaj volumio[923]: info: No input data
Jan 31 20:53:02 musikpaj volumio[923]: info: No input data
Jan 31 20:53:02 musikpaj volumio[923]: info: No input data
Jan 31 20:53:02 musikpaj volumio[923]: info: No input data
Jan 31 20:53:02 musikpaj volumio[923]: info: No input data
Jan 31 20:53:10 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Jan 31 20:53:10 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 31 20:53:15 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Jan 31 20:53:51 musikpaj volumio[923]: info: CoreCommandRouter::volumioGetState
Jan 31 20:53:51 musikpaj volumio[923]: info: [1517432031481] CoreStateMachine::getState
Jan 31 20:53:51 musikpaj volumio[923]: info: [1517432031483] CorePlayQueue::getTrack 0
Jan 31 20:53:51 musikpaj volumio[923]: info:
Jan 31 20:53:51 musikpaj volumio[923]: [1517432031485] ---------------------------- Client requests Volumio state
Jan 31 20:53:51 musikpaj volumio[923]: info: [1517432031489] InterfaceWebUI::pushState
Jan 31 20:53:51 musikpaj volumio[923]: info: [1517432031508] ------------------------------ 29ms
Jan 31 20:53:57 musikpaj wpa_supplicant[858]: wlan0: BSS: Remove id 5 BSSID b0:e1:7e:f3:46:ce SSID '3Bredband-2.4G-46CE' due to wpa_bss_flush_by_age
Jan 31 20:53:57 musikpaj wpa_supplicant[858]: wlan0: BSS: Remove id 6 BSSID 0c:96:bf:4d:1c:b1 SSID 'Not' due to wpa_bss_flush_by_age
Jan 31 20:53:57 musikpaj wpa_supplicant[858]: wlan0: BSS: Remove id 7 BSSID 0c:51:01:e4:79:d0 SSID 'Urbans Wi-Fi' due to wpa_bss_flush_by_age
Jan 31 20:54:11 musikpaj volumio[923]: (node:923) UnhandledPromiseRejectionWarning: Unhandled promise rejection (rejection id: 2): Error
Jan 31 20:54:14 musikpaj ntpd_intres[725]: host name not found: 0.debian.pool.ntp.org
Jan 31 20:54:26 musikpaj volumio[923]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 31 20:54:26 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork
Jan 31 20:54:26 musikpaj sudo[3952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0
Jan 31 20:54:26 musikpaj sudo[3952]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 31 20:54:26 musikpaj sudo[3952]: pam_unix(sudo:session): session closed for user root
Jan 31 20:54:26 musikpaj sudo[3965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Jan 31 20:54:26 musikpaj sudo[3965]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 31 20:54:26 musikpaj sudo[3965]: pam_unix(sudo:session): session closed for user root
Jan 31 20:54:26 musikpaj sudo[3979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Jan 31 20:54:26 musikpaj sudo[3979]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 31 20:54:26 musikpaj sudo[3979]: pam_unix(sudo:session): session closed for user root
Jan 31 20:54:26 musikpaj sudo[3990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Jan 31 20:54:26 musikpaj sudo[3990]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 31 20:54:26 musikpaj sudo[3990]: pam_unix(sudo:session): session closed for user root
Jan 31 20:54:28 musikpaj sudo[4003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jan 31 20:54:28 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jan 31 20:54:28 musikpaj sudo[4003]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 31 20:54:28 musikpaj sudo[4008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jan 31 20:54:28 musikpaj sudo[4003]: pam_unix(sudo:session): session closed for user root
Jan 31 20:54:28 musikpaj sudo[4008]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 31 20:54:28 musikpaj sudo[4008]: pam_unix(sudo:session): session closed for user root
Jan 31 20:54:28 musikpaj sudo[4020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jan 31 20:54:28 musikpaj sudo[4020]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 31 20:54:28 musikpaj wpa_supplicant[858]: nl80211: Drv Event 33 (NL80211_CMD_TRIGGER_SCAN) received for wlan0
Jan 31 20:54:28 musikpaj wpa_supplicant[858]: wlan0: nl80211: Scan trigger
Jan 31 20:54:28 musikpaj wpa_supplicant[858]: wlan0: Event SCAN_STARTED (49) received
Jan 31 20:54:28 musikpaj wpa_supplicant[858]: wlan0: External program started a scan
Jan 31 20:54:28 musikpaj wpa_supplicant[858]: RTM_NEWLINK: ifi_index=3 ifname=wlan0 wext ifi_family=0 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
Jan 31 20:54:28 musikpaj wpa_supplicant[858]: nl80211: Drv Event 34 (NL80211_CMD_NEW_SCAN_RESULTS) received for wlan0
Jan 31 20:54:28 musikpaj wpa_supplicant[858]: wlan0: nl80211: New scan results available
Jan 31 20:54:28 musikpaj wpa_supplicant[858]: nl80211: Scan probed for SSID ''
Jan 31 20:54:28 musikpaj wpa_supplicant[858]: nl80211: Scan included frequencies: 2412 2417 2422 2427 2432 2437 2442 2447 2452 2457 2462
Jan 31 20:54:28 musikpaj wpa_supplicant[858]: wlan0: Event SCAN_RESULTS (3) received
Jan 31 20:54:28 musikpaj wpa_supplicant[858]: wlan0: Scan completed in 0.711057 seconds
Jan 31 20:54:28 musikpaj wpa_supplicant[858]: nl80211: Associated on 2462 MHz
Jan 31 20:54:28 musikpaj wpa_supplicant[858]: nl80211: Associated with 44:6e:e5:3b:45:dd
Jan 31 20:54:28 musikpaj wpa_supplicant[858]: nl80211: Received scan results (6 BSSes)
Jan 31 20:54:28 musikpaj wpa_supplicant[858]: nl80211: Scan results indicate BSS status with 44:6e:e5:3b:45:dd as associated
Jan 31 20:54:28 musikpaj wpa_supplicant[858]: wlan0: BSS: Start scan result update 3
Jan 31 20:54:28 musikpaj wpa_supplicant[858]: wlan0: BSS: Add new id 8 BSSID b0:e1:7e:f3:46:ce SSID '3Bredband-2.4G-46CE'
Jan 31 20:54:28 musikpaj wpa_supplicant[858]: wlan0: BSS: Add new id 9 BSSID 64:a5:c3:68:fb:68 SSID 'Urbans Wi-Fi'
Jan 31 20:54:28 musikpaj wpa_supplicant[858]: wlan0: BSS: Add new id 10 BSSID 0c:51:01:e4:79:d0 SSID 'Urbans Wi-Fi'
Jan 31 20:54:28 musikpaj wpa_supplicant[858]: wlan0: BSS: Add new id 11 BSSID 0c:96:bf:4d:1c:b1 SSID 'Not'
Jan 31 20:54:28 musikpaj wpa_supplicant[858]: wlan0: BSS: Add new id 12 BSSID f2:9f:c2:3d:04:e5 SSID 'Xercise_gast'
Jan 31 20:54:28 musikpaj wpa_supplicant[858]: BSS: last_scan_res_used=6/32
Jan 31 20:54:28 musikpaj wpa_supplicant[858]: wlan0: New scan results available (own=0 ext=1)
Jan 31 20:54:28 musikpaj wpa_supplicant[858]: wlan0: Do not use results from externally requested scan operation for network selection
Jan 31 20:54:28 musikpaj sudo[4020]: pam_unix(sudo:session): session closed for user root
Jan 31 20:54:36 musikpaj volumio[923]: info:
Jan 31 20:54:36 musikpaj volumio[923]: [1517432076940] ---------------------------- Client requests Menu Items
Jan 31 20:54:36 musikpaj volumio[923]: info: [1517432076982] ------------------------------ 42ms
Jan 31 20:54:36 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jan 31 20:54:36 musikpaj volumio[923]: Plugin multiroom or method getMultiroom not found
Jan 31 20:54:36 musikpaj volumio[923]: info: [1517432076997] Listing playlists
Jan 31 20:54:37 musikpaj volumio[923]: info: CoreCommandRouter::volumioGetBrowseSources
Jan 31 20:54:37 musikpaj volumio[923]: info: CoreCommandRouter::volumioGetQueue
Jan 31 20:54:37 musikpaj volumio[923]: info: [1517432077012] CoreStateMachine::getQueue
Jan 31 20:54:37 musikpaj volumio[923]: info: [1517432077014] CorePlayQueue::getQueue
Jan 31 20:54:37 musikpaj volumio[923]: info:
Jan 31 20:54:37 musikpaj volumio[923]: [1517432077016] ---------------------------- Client requests Volumio queue
Jan 31 20:54:37 musikpaj volumio[923]: info: [1517432077019] InterfaceWebUI::pushQueue
Jan 31 20:54:37 musikpaj volumio[923]: info: [1517432077021] ------------------------------ 11ms
Jan 31 20:54:37 musikpaj volumio[923]: info:
Jan 31 20:54:37 musikpaj volumio[923]: [1517432077024] ---------------------------- Client requests Menu Items
Jan 31 20:54:37 musikpaj volumio[923]: info:
Jan 31 20:54:37 musikpaj volumio[923]: [1517432077029] ---------------------------- Client requests Menu Items
Jan 31 20:54:37 musikpaj volumio[923]: info: [1517432077039] ------------------------------ 15ms
Jan 31 20:54:37 musikpaj volumio[923]: info: [1517432077070] ------------------------------ 41ms
Jan 31 20:55:03 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri
Jan 31 20:55:08 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri
Jan 31 20:55:08 musikpaj volumio[923]: info: [1517432108992] ControllerSpop::sendSpopCommand
Jan 31 20:55:08 musikpaj volumio[923]: info: ADDING DEFER FOR COMMAND ls
Jan 31 20:55:09 musikpaj volumio[923]: info: SPOP command error:
Jan 31 20:55:09 musikpaj volumio[923]: info:  Error: This socket has been ended by the other party
Jan 31 20:55:09 musikpaj volumio[923]: at Socket.writeAfterFIN [as write] (net.js:290:12)
Jan 31 20:55:09 musikpaj volumio[923]: at applyWithContext (/data/plugins/music_service/spop/node_modules/fast.js/function/applyWithContext.js:15:22)
Jan 31 20:55:09 musikpaj volumio[923]: at /data/plugins/music_service/spop/node_modules/fast.js/function/bind.js:63:14
Jan 31 20:55:09 musikpaj volumio[923]: at onBoundPromise (/data/plugins/music_service/spop/node_modules/kew/kew.js:834:10)
Jan 31 20:55:09 musikpaj volumio[923]: at ncall (/data/plugins/music_service/spop/node_modules/kew/kew.js:816:44)
Jan 31 20:55:09 musikpaj volumio[923]: at Object.nfcall (/data/plugins/music_service/spop/node_modules/kew/kew.js:803:16)
Jan 31 20:55:09 musikpaj volumio[923]: at Promise._successFn (/data/plugins/music_service/spop/index.js:1207:16)
Jan 31 20:55:09 musikpaj volumio[923]: at nextTickCallback (/data/plugins/music_service/spop/node_modules/kew/kew.js:47:28)
Jan 31 20:55:09 musikpaj volumio[923]: at _combinedTickCallback (internal/process/next_tick.js:73:7)
Jan 31 20:55:09 musikpaj volumio[923]: at process._tickCallback (internal/process/next_tick.js:104:9)
Jan 31 20:55:10 musikpaj ntpd_intres[725]: host name not found: 1.debian.pool.ntp.org
Jan 31 20:55:46 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri
Jan 31 20:55:46 musikpaj volumio[923]: info: [1517432146215] ControllerSpop::sendSpopCommand
Jan 31 20:55:46 musikpaj volumio[923]: info: ADDING DEFER FOR COMMAND ls
Jan 31 20:55:46 musikpaj volumio[923]: info: SPOP command error:
Jan 31 20:55:46 musikpaj volumio[923]: info:  Error: This socket has been ended by the other party
Jan 31 20:55:46 musikpaj volumio[923]: at Socket.writeAfterFIN [as write] (net.js:290:12)
Jan 31 20:55:46 musikpaj volumio[923]: at applyWithContext (/data/plugins/music_service/spop/node_modules/fast.js/function/applyWithContext.js:15:22)
Jan 31 20:55:46 musikpaj volumio[923]: at /data/plugins/music_service/spop/node_modules/fast.js/function/bind.js:63:14
Jan 31 20:55:46 musikpaj volumio[923]: at onBoundPromise (/data/plugins/music_service/spop/node_modules/kew/kew.js:834:10)
Jan 31 20:55:46 musikpaj volumio[923]: at ncall (/data/plugins/music_service/spop/node_modules/kew/kew.js:816:44)
Jan 31 20:55:46 musikpaj volumio[923]: at Object.nfcall (/data/plugins/music_service/spop/node_modules/kew/kew.js:803:16)
Jan 31 20:55:46 musikpaj volumio[923]: at Promise._successFn (/data/plugins/music_service/spop/index.js:1207:16)
Jan 31 20:55:46 musikpaj volumio[923]: at nextTickCallback (/data/plugins/music_service/spop/node_modules/kew/kew.js:47:28)
Jan 31 20:55:46 musikpaj volumio[923]: at _combinedTickCallback (internal/process/next_tick.js:73:7)
Jan 31 20:55:46 musikpaj volumio[923]: at process._tickCallback (internal/process/next_tick.js:104:9)
Jan 31 20:56:06 musikpaj volumio[923]: info: CoreCommandRouter::volumioGetState
Jan 31 20:56:06 musikpaj volumio[923]: info: [1517432166066] CoreStateMachine::getState
Jan 31 20:56:06 musikpaj volumio[923]: info: [1517432166067] CorePlayQueue::getTrack 0
Jan 31 20:56:06 musikpaj volumio[923]: info:
Jan 31 20:56:06 musikpaj volumio[923]: [1517432166068] ---------------------------- Client requests Volumio state
Jan 31 20:56:06 musikpaj volumio[923]: info: [1517432166069] InterfaceWebUI::pushState
Jan 31 20:56:06 musikpaj volumio[923]: info: [1517432166087] ------------------------------ 21ms
Jan 31 20:56:06 musikpaj ntpd_intres[725]: host name not found: 2.debian.pool.ntp.org
Jan 31 20:56:06 musikpaj volumio[923]: info:
Jan 31 20:56:06 musikpaj volumio[923]: [1517432166959] ---------------------------- Client requests Menu Items
Jan 31 20:56:06 musikpaj volumio[923]: info: [1517432166976] ------------------------------ 17ms
Jan 31 20:56:07 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jan 31 20:56:07 musikpaj volumio[923]: Plugin multiroom or method getMultiroom not found
Jan 31 20:56:07 musikpaj volumio[923]: info: [1517432167003] Listing playlists
Jan 31 20:56:07 musikpaj volumio[923]: info: CoreCommandRouter::volumioGetBrowseSources
Jan 31 20:56:07 musikpaj volumio[923]: info: CoreCommandRouter::volumioGetQueue
Jan 31 20:56:07 musikpaj volumio[923]: info: [1517432167012] CoreStateMachine::getQueue
Jan 31 20:56:07 musikpaj volumio[923]: info: [1517432167013] CorePlayQueue::getQueue
Jan 31 20:56:07 musikpaj volumio[923]: info:
Jan 31 20:56:07 musikpaj volumio[923]: [1517432167013] ---------------------------- Client requests Volumio queue
Jan 31 20:56:07 musikpaj volumio[923]: info: [1517432167014] InterfaceWebUI::pushQueue
Jan 31 20:56:07 musikpaj volumio[923]: info: [1517432167016] ------------------------------ 5ms
Jan 31 20:56:07 musikpaj volumio[923]: info:
Jan 31 20:56:07 musikpaj volumio[923]: [1517432167018] ---------------------------- Client requests Menu Items
Jan 31 20:56:07 musikpaj volumio[923]: info:
Jan 31 20:56:07 musikpaj volumio[923]: [1517432167024] ---------------------------- Client requests Menu Items
Jan 31 20:56:07 musikpaj volumio[923]: info: [1517432167043] ------------------------------ 26ms
Jan 31 20:56:07 musikpaj volumio[923]: info: [1517432167056] ------------------------------ 32ms
Jan 31 20:56:13 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri
Jan 31 20:56:27 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri
Jan 31 20:56:35 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri
Jan 31 20:56:35 musikpaj volumio[923]: info: [1517432195671] ControllerSpop::sendSpopCommand
Jan 31 20:56:35 musikpaj volumio[923]: info: ADDING DEFER FOR COMMAND ls
Jan 31 20:56:35 musikpaj volumio[923]: info: SPOP command error:
Jan 31 20:56:35 musikpaj volumio[923]: info:  Error: This socket has been ended by the other party
Jan 31 20:56:35 musikpaj volumio[923]: at Socket.writeAfterFIN [as write] (net.js:290:12)
Jan 31 20:56:35 musikpaj volumio[923]: at applyWithContext (/data/plugins/music_service/spop/node_modules/fast.js/function/applyWithContext.js:15:22)
Jan 31 20:56:35 musikpaj volumio[923]: at /data/plugins/music_service/spop/node_modules/fast.js/function/bind.js:63:14
Jan 31 20:56:35 musikpaj volumio[923]: at onBoundPromise (/data/plugins/music_service/spop/node_modules/kew/kew.js:834:10)
Jan 31 20:56:35 musikpaj volumio[923]: at ncall (/data/plugins/music_service/spop/node_modules/kew/kew.js:816:44)
Jan 31 20:56:35 musikpaj volumio[923]: at Object.nfcall (/data/plugins/music_service/spop/node_modules/kew/kew.js:803:16)
Jan 31 20:56:35 musikpaj volumio[923]: at Promise._successFn (/data/plugins/music_service/spop/index.js:1207:16)
Jan 31 20:56:35 musikpaj volumio[923]: at nextTickCallback (/data/plugins/music_service/spop/node_modules/kew/kew.js:47:28)
Jan 31 20:56:35 musikpaj volumio[923]: at _combinedTickCallback (internal/process/next_tick.js:73:7)
Jan 31 20:56:35 musikpaj volumio[923]: at process._tickCallback (internal/process/next_tick.js:104:9)
Jan 31 20:57:00 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jan 31 20:57:01 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jan 31 20:57:02 musikpaj volumio[923]: info: No input data
Jan 31 20:57:02 musikpaj ntpd_intres[725]: host name not found: 3.debian.pool.ntp.org
Jan 31 20:57:03 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jan 31 20:57:03 musikpaj volumio[923]: info: No input data
Jan 31 20:57:05 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jan 31 20:57:07 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jan 31 20:57:10 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jan 31 20:57:10 musikpaj volumio[923]: info: No input data
Jan 31 20:57:12 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jan 31 20:57:17 musikpaj volumio[923]: info: CoreCommandRouter::volumioAddQueueItems
Jan 31 20:57:17 musikpaj volumio[923]: info: [1517432237898] CoreStateMachine::addQueueItems
Jan 31 20:57:17 musikpaj volumio[923]: info: [1517432237900] CorePlayQueue::addQueueItems
Jan 31 20:57:17 musikpaj volumio[923]: info: Adding Item to queue: upnp/http://192.168.8.105:32469/ContentDirectory/b6d69b83-876f-c05a-7c9b-57b0c7858485/control.xml@a0928d7411a743d7d87f
Jan 31 20:57:17 musikpaj volumio[923]: info: Exploding uri upnp/http://192.168.8.105:32469/ContentDirectory/b6d69b83-876f-c05a-7c9b-57b0c7858485/control.xml@a0928d7411a743d7d87f in service upnp_browser
Jan 31 20:57:17 musikpaj volumio[923]: info: [1517432237974] CorePlayQueue::saveQueue
Jan 31 20:57:17 musikpaj volumio[923]: info: CoreCommandRouter::volumioPushQueue
Jan 31 20:57:17 musikpaj volumio[923]: info: [1517432237978] interfaceApi::pushQueue
Jan 31 20:57:17 musikpaj volumio[923]: info: [1517432237979] InterfaceWebUI::pushQueue
Jan 31 20:57:17 musikpaj volumio[923]: info: [1517432237987] CoreStateMachine::updateTrackBlock
Jan 31 20:57:17 musikpaj volumio[923]: info: [1517432237988] CorePlayQueue::getTrackBlock
Jan 31 20:57:17 musikpaj volumio[923]: info: CoreCommandRouter::volumioPlay
Jan 31 20:57:17 musikpaj volumio[923]: UNSET VOLATILE
Jan 31 20:57:17 musikpaj volumio[923]: info: [1517432237992] CoreStateMachine::play index 0
Jan 31 20:57:17 musikpaj volumio[923]: info: [1517432237993] CoreStateMachine::setConsumeUpdateService undefined
Jan 31 20:57:17 musikpaj volumio[923]: info: [1517432237996] CoreStateMachine::stop
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432237997] CoreStateMachine::setConsumeUpdateService undefined
Jan 31 20:57:18 musikpaj volumio[923]: UNSET VOLATILE
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432237998] CoreStateMachine::play index undefined
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432237999] CoreStateMachine::setConsumeUpdateService undefined
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238000] CorePlayQueue::getTrack 0
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238002] CoreStateMachine::startPlaybackTimer
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238003] CorePlayQueue::getTrack 0
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238006] ControllerUPNPBrowser::clearAddPlayTrack
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238007] ControllerMpd::sendMpdCommand stop
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238015] sending command...
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238026] parsing response...
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238028] ControllerMpd::sendMpdCommand clear
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238029] sending command...
Jan 31 20:57:18 musikpaj volumio[923]: info:
Jan 31 20:57:18 musikpaj volumio[923]: [1517432238032] ---------------------------- MPD announces system playlist update
Jan 31 20:57:18 musikpaj volumio[923]: info: Ignoring MPD Status Update
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238035] parsing response...
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238037] ControllerMpd::sendMpdCommand load "http://192.168.8.105:32469/object/a0928d7411a743d7d87f/file.mp3"
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238038] sending command...
Jan 31 20:57:18 musikpaj volumio[923]: info:
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238042] ------------------------------ 10ms
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238103] ControllerMpd::sendMpdCommand add "http://192.168.8.105:32469/object/a0928d7411a743d7d87f/file.mp3"
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238103] sending command...
Jan 31 20:57:18 musikpaj volumio[923]: info:
Jan 31 20:57:18 musikpaj volumio[923]: [1517432238105] ---------------------------- MPD announces system playlist update
Jan 31 20:57:18 musikpaj volumio[923]: info: Ignoring MPD Status Update
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238107] parsing response...
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238108] CoreStateMachine::setConsumeUpdateService mpd
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238109] ControllerMpd::sendMpdCommand play
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238110] sending command...
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238117] ------------------------------ 12ms
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238118] parsing response...
Jan 31 20:57:18 musikpaj volumio[923]: info:
Jan 31 20:57:18 musikpaj volumio[923]: [1517432238209] ---------------------------- MPD announces state update: player
Jan 31 20:57:18 musikpaj kernel: pcm512x 1-004c: No SCLK, using BCLK: -2
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238212] ControllerMpd::getState
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238213] ControllerMpd::sendMpdCommand status
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238214] sending command...
Jan 31 20:57:18 musikpaj volumio[923]: info:
Jan 31 20:57:18 musikpaj volumio[923]: [1517432238217] ---------------------------- MPD announces state update: player
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238222] parsing response...
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238225] ControllerMpd::getState
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238228] ControllerMpd::sendMpdCommand status
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238232] ControllerMpd::parseState
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238236] ControllerMpd::sendMpdCommand playlistinfo
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238238] sending command...
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238242] sending command...
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238247] parsing response...
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238250] parsing response...
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238264] ControllerMpd::parseState
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238267] ControllerMpd::sendMpdCommand playlistinfo
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238276] ControllerMpd::parseTrackInfo
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238280] sending command...
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238282] ControllerMpd::pushState
Jan 31 20:57:18 musikpaj volumio[923]: info: CoreCommandRouter::servicePushState
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238291] CoreStateMachine::syncState
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238293] CorePlayQueue::getTrack 0
Jan 31 20:57:18 musikpaj volumio[923]: info: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":172,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.8.105:32469/object/a0928d7411a743d7d87f/file.mp3","trackType":"mp3"}
Jan 31 20:57:18 musikpaj volumio[923]: info: CURRENT POSITION 0
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238300] CoreStateMachine::syncState   stateService play
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238302] CoreStateMachine::syncState   currentStatus stop
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238304] CoreStateMachine::pushState
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238306] CoreStateMachine::getState
Jan 31 20:57:18 musikpaj volumio[923]: info: CoreCommandRouter::volumioPushState
Jan 31 20:57:18 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238313] interfaceApi::pushState
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238315] InterfaceWebUI::pushState
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238340] ------------------------------ 131ms
Jan 31 20:57:18 musikpaj volumio[923]: info: Pushing Favourites {"service":"mpd","favourite":false}
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238352] parsing response...
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238353] ControllerMpd::parseTrackInfo
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238355] ControllerMpd::pushState
Jan 31 20:57:18 musikpaj volumio[923]: info: CoreCommandRouter::servicePushState
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238357] CoreStateMachine::syncState
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238359] CorePlayQueue::getTrack 0
Jan 31 20:57:18 musikpaj volumio[923]: info: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":172,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.8.105:32469/object/a0928d7411a743d7d87f/file.mp3","trackType":"mp3"}
Jan 31 20:57:18 musikpaj volumio[923]: info: CURRENT POSITION 0
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238363] CoreStateMachine::syncState   stateService play
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238366] CoreStateMachine::syncState   currentStatus play
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238367] Received an update from plaugin. extracting info from payload
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238369] CoreStateMachine::pushState
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238370] CoreStateMachine::getState
Jan 31 20:57:18 musikpaj volumio[923]: info: CoreCommandRouter::volumioPushState
Jan 31 20:57:18 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238378] interfaceApi::pushState
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238379] InterfaceWebUI::pushState
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238419] CoreStateMachine::pushState
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238421] CoreStateMachine::getState
Jan 31 20:57:18 musikpaj volumio[923]: info: CoreCommandRouter::volumioPushState
Jan 31 20:57:18 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238427] interfaceApi::pushState
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238428] InterfaceWebUI::pushState
Jan 31 20:57:18 musikpaj volumio[923]: info: [1517432238455] ------------------------------ 238ms
Jan 31 20:57:18 musikpaj volumio[923]: info: Pushing Favourites {"service":"mpd","uri":"http://192.168.8.105:32469/object/a0928d7411a743d7d87f/file.mp3","favourite":false}
Jan 31 20:57:18 musikpaj volumio[923]: info: Pushing Favourites {"service":"mpd","uri":"http://192.168.8.105:32469/object/a0928d7411a743d7d87f/file.mp3","favourite":false}
Jan 31 20:57:29 musikpaj volumio[923]: info:
Jan 31 20:57:29 musikpaj volumio[923]: [1517432249282] ---------------------------- Client requests Volume 49
Jan 31 20:57:29 musikpaj volumio[923]: info: [1517432249285] VolumeController::SetAlsaVolume49
Jan 31 20:57:29 musikpaj volumio[923]: info: [1517432249303] ------------------------------ 21ms
Jan 31 20:57:29 musikpaj volumio[923]: null
Jan 31 20:57:29 musikpaj volumio[923]: info: [1517432249318] VolumeController::Volume 49
Jan 31 20:57:29 musikpaj volumio[923]: info: [1517432249319] CoreStateMachine::pushState
Jan 31 20:57:29 musikpaj volumio[923]: info: [1517432249320] CoreStateMachine::getState
Jan 31 20:57:29 musikpaj volumio[923]: info: CoreCommandRouter::volumioPushState
Jan 31 20:57:29 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 31 20:57:29 musikpaj volumio[923]: info: [1517432249323] interfaceApi::pushState
Jan 31 20:57:29 musikpaj volumio[923]: info: [1517432249324] InterfaceWebUI::pushState
Jan 31 20:57:29 musikpaj volumio[923]: info: Pushing Favourites {"service":"mpd","uri":"http://192.168.8.105:32469/object/a0928d7411a743d7d87f/file.mp3","favourite":false}
Jan 31 20:57:38 musikpaj wpa_supplicant[858]: wlan0: BSS: Remove id 8 BSSID b0:e1:7e:f3:46:ce SSID '3Bredband-2.4G-46CE' due to wpa_bss_flush_by_age
Jan 31 20:57:38 musikpaj wpa_supplicant[858]: wlan0: BSS: Remove id 9 BSSID 64:a5:c3:68:fb:68 SSID 'Urbans Wi-Fi' due to wpa_bss_flush_by_age
Jan 31 20:57:38 musikpaj wpa_supplicant[858]: wlan0: BSS: Remove id 10 BSSID 0c:51:01:e4:79:d0 SSID 'Urbans Wi-Fi' due to wpa_bss_flush_by_age
Jan 31 20:57:38 musikpaj wpa_supplicant[858]: wlan0: BSS: Remove id 11 BSSID 0c:96:bf:4d:1c:b1 SSID 'Not' due to wpa_bss_flush_by_age
Jan 31 20:57:38 musikpaj wpa_supplicant[858]: wlan0: BSS: Remove id 12 BSSID f2:9f:c2:3d:04:e5 SSID 'Xercise_gast' due to wpa_bss_flush_by_age
Jan 31 20:57:41 musikpaj volumio[923]: info:
Jan 31 20:57:41 musikpaj volumio[923]: [1517432261425] ---------------------------- Client requests Volumio pause
Jan 31 20:57:41 musikpaj volumio[923]: info: CoreCommandRouter::volumioPause
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261428] CoreStateMachine::pause
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261429] CoreStateMachine::stPlaybackTimer
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261431] CoreStateMachine::servicePause
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261432] CorePlayQueue::getTrack 0
Jan 31 20:57:41 musikpaj volumio[923]: info: CoreCommandRouter::servicePause
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261435] ControllerUPNPBrowser::pause
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261437] ControllerMpd::sendMpdCommand pause
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261439] sending command...
Jan 31 20:57:41 musikpaj volumio[923]: info:
Jan 31 20:57:41 musikpaj volumio[923]: [1517432261516] ---------------------------- MPD announces state update: player
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261517] parsing response...
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261518] ControllerMpd::getState
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261519] ControllerMpd::sendMpdCommand status
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261521] ------------------------------ 96ms
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261522] sending command...
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261526] parsing response...
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261527] ControllerMpd::parseState
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261528] ControllerMpd::sendMpdCommand playlistinfo
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261529] sending command...
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261531] parsing response...
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261532] ControllerMpd::parseTrackInfo
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261533] ControllerMpd::pushState
Jan 31 20:57:41 musikpaj volumio[923]: info: CoreCommandRouter::servicePushState
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261535] CoreStateMachine::syncState
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261536] CorePlayQueue::getTrack 0
Jan 31 20:57:41 musikpaj volumio[923]: info: STATE SERVICE {"status":"pause","position":0,"seek":23684,"duration":172,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.8.105:32469/object/a0928d7411a743d7d87f/file.mp3","trackType":"mp3"}
Jan 31 20:57:41 musikpaj volumio[923]: info: CURRENT POSITION 0
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261538] CoreStateMachine::syncState   stateService pause
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261539] CoreStateMachine::syncState   currentStatus pause
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261540] CoreStateMachine::pushState
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261541] CoreStateMachine::getState
Jan 31 20:57:41 musikpaj volumio[923]: info: CoreCommandRouter::volumioPushState
Jan 31 20:57:41 musikpaj volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261545] interfaceApi::pushState
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261546] InterfaceWebUI::pushState
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261566] CoreStateMachine::stPlaybackTimer
Jan 31 20:57:41 musikpaj volumio[923]: info: [1517432261569] ------------------------------ 53ms
Jan 31 20:57:41 musikpaj volumio[923]: info: Pushing Favourites {"service":"mpd","uri":"http://192.168.8.105:32469/object/a0928d7411a743d7d87f/file.mp3","favourite":false}