Your browser was unable to load all of the resources. They may have been blocked by your firewall, proxy or browser configuration.
Press Ctrl+F5 or Ctrl+Shift+R to have your browser try again.

Unable to create music collection (Could not write to socket) #49

nGaDOLEr · · author
#1

Hi all,

I am about to set up a mopidy server with RompR using Docker compose on the latest openmediavault.
I configured mopidy, did a local scan and then started the RompR setup. On my first try I got the following: "There was an error communicating with Mopidy! (could not write to socket)". However, when I hit OK again I reached the GUI and could update my collection.
After a few seconds I got the confirmation that the collection was updated, but had no files in it.

I logged everything with Level 8 debugging and got the following:

2021/01/30 22:14:53 [error] 21#21: *2 FastCGI sent in stderr: "PHP message: LOG   : INIT              : ******++++++======------******------======++++++******PHP message: BLURT : INIT              : Detecting window size to decide which skin to use" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET / HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050"
2021/01/30 22:14:53 [error] 21#21: *2 FastCGI sent in stderr: "PHP message: LOG   : INIT              : ******++++++======------******------======++++++******PHP message: LOG   : INIT              : Loading Skin Requirements FilePHP message: MARK  : INIT              : Attempting to connect to player DefaultPHP message: MARK  : INIT              : Player backend cookie is not setPHP message: TRACE : MPDPLAYER         : Creating Player for localhost:6600PHP message: ERROR : INIT              : MPD Connection FailurePHP message: FAIL  : INIT              : MPD Connection FailedPHP message: LOG   : SETUP             : Displaying Setup Screen" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET / HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/"
2021/01/30 22:14:53 [error] 21#21: *5 FastCGI sent in stderr: "PHP message: LOG   : THEME             : Theme       : Numismatist.cssPHP message: LOG   : THEME             : Font        : Nunito.cssPHP message: LOG   : THEME             : Font Size   : 02-Normal.cssPHP message: LOG   : THEME             : Cover Size  : 40-Large.cssPHP message: LOG   : THEME             : Icons       : New-Dark-Circled" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /gettheme.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/"
2021/01/30 22:15:20 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: LOG   : INIT              : ******++++++======------******------======++++++******PHP message: LOG   : INIT              : Loading Skin Requirements FilePHP message: MARK  : INIT              : Setting Pref currenthost to DefaultPHP message: MARK  : INIT              : Setting Pref mpd_host to <myIP>PHP message: MARK  : INIT              : Setting Pref mpd_port to 6600PHP message: MARK  : INIT              : Setting Pref mpd_password to PHP message: MARK  : INIT              : Setting Pref unix_socket to PHP message: MARK  : INIT              : Setting Pref cleanalbumimages to 1PHP message: MARK  : INIT              : Setting Pref google_api_key to PHP message: MARK  : INIT              : Setting Pref google_search_engine_id to PHP message: MARK  : INIT              : Setting Pref collection_type to mysqlPHP message: MARK  : INIT              : Setting Pref mysql_host to <myIP>PHP message: MARK  : INIT              : Setting Pref mysql_port to 33060PHP message: MARK  : INIT              : Setting Pref mysql_database to romprdbPHP message: MARK  : INIT              : Setting Pref mysql_user to romprPHP message: MARK  : INIT              : Setting Pref mysql_password to romprdbpassPHP message: MARK  : INIT              : Setting Pref proxy_host to PHP message: MARK  : INIT              : Setting Pref proxy_user to PHP message: MARK  : INIT              : Setting Pref proxy_password to PHP message: MARK  : INIT              : Setting Pref debug_enabled to 8PHP message: MARK  : INIT              : Setting Pref custom_logfile to PHP message: MARK  : INIT              : Setting Pref do_not_show_prefs to PHP message: MARK  : INIT              : Setting Pref separate_collections to PHP message: MARK  : INIT              : Attempting to connect to player DefaultPHP message: MARK  : INIT              : Player backend cookie is not setPHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600PHP message: SHOUT 
2021/01/30 22:15:20 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: LOG   : THEME             : Theme       : Numismatist.cssPHP message: LOG   : THEME             : Font        : Nunito.cssPHP message: LOG   : THEME             : Font Size   : 02-Normal.cssPHP message: LOG   : THEME             : Cover Size  : 40-Large.cssPHP message: LOG   : THEME             : Icons       : New-Dark-Circled" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /gettheme.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:00 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: LOG   : INIT              : ******++++++======------******------======++++++******PHP message: LOG   : INIT              : Loading Skin Requirements FilePHP message: MARK  : INIT              : Setting Pref currenthost to DefaultPHP message: MARK  : INIT              : Setting Pref mpd_host to <myIP>PHP message: MARK  : INIT              : Setting Pref mpd_port to 6600PHP message: MARK  : INIT              : Setting Pref mpd_password to PHP message: MARK  : INIT              : Setting Pref unix_socket to PHP message: MARK  : INIT              : Setting Pref cleanalbumimages to 1PHP message: MARK  : INIT              : Setting Pref google_api_key to PHP message: MARK  : INIT              : Setting Pref google_search_engine_id to PHP message: MARK  : INIT              : Setting Pref collection_type to mysqlPHP message: MARK  : INIT              : Setting Pref mysql_host to <myIP>PHP message: MARK  : INIT              : Setting Pref mysql_port to 33060PHP message: MARK  : INIT              : Setting Pref mysql_database to romprdbPHP message: MARK  : INIT              : Setting Pref mysql_user to romprPHP message: MARK  : INIT              : Setting Pref mysql_password to romprdbpassPHP message: MARK  : INIT              : Setting Pref proxy_host to PHP message: MARK  : INIT              : Setting Pref proxy_user to PHP message: MARK  : INIT              : Setting Pref proxy_password to PHP message: MARK  : INIT              : Setting Pref debug_enabled to 8PHP message: MARK  : INIT              : Setting Pref custom_logfile to PHP message: MARK  : INIT              : Setting Pref do_not_show_prefs to PHP message: MARK  : INIT              : Setting Pref separate_collections to PHP message: MARK  : INIT              : Attempting to connect to player DefaultPHP message: MARK  : INIT              : Player backend cookie is mopidyPHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600PHP message: ERROR :
2021/01/30 22:16:00 [error] 23#23: *7 FastCGI sent in stderr: "P message: WARN  : GENERIC_SQL       : Command Failed : CREATE TRIGGER rating_insert_trigger AFTER INSERT ON Ratingtable
						FOR EACH ROW
						BEGIN
						UPDATE Albumtable SET justUpdated = 1 WHERE Albumindex = (SELECT Albumindex FROM Tracktable WHERE TTindex = NEW.TTindex);
						UPDATE Tracktable SET Hidden = 0, justAdded = 1 WHERE Hidden = 1 AND TTindex = NEW.TTindex;
						UPDATE Tracktable SET isSearchResult = 1, LastModified = NULL, justAdded = 1 WHERE isSearchResult > 1 AND TTindex = NEW.TTindex;
						END;PHP message: ERROR : MYSQL ERROR       :  : 1419 : You do not have the SUPER privilege and binary logging is enabled (you *might* want to use the less safe log_bin_trust_function_creators variable)PHP message: WARN  : GENERIC_SQL       : Command Failed : CREATE TRIGGER tag_delete_trigger AFTER DELETE ON Tagtable
						FOR EACH ROW
						BEGIN
						DELETE FROM TagListtable WHERE Tagindex = OLD.Tagindex;
						END;PHP message: ERROR : MYSQL ERROR       :  : 1419 : You do not have the SUPER privilege and binary logging is enabled (you *might* want to use the less safe log_bin_trust_function_creators variable)PHP message: WARN  : GENERIC_SQL       : Command Failed : CREATE TRIGGER tag_insert_trigger AFTER INSERT ON TagListtable
						FOR EACH ROW
						BEGIN
						UPDATE Albumtable SET justUpdated = 1 WHERE Albumindex = (SELECT Albumindex FROM Tracktable WHERE TTindex = NEW.TTindex);
						UPDATE Tracktable SET Hidden = 0, justAdded = 1 WHERE Hidden = 1 AND TTindex = NEW.TTindex;
						UPDATE Tracktable SET isSearchResult = 1, LastModified = NULL, justAdded = 1 WHERE isSearchResult > 1 AND TTindex = NEW.TTindex;
						END;PHP message: ERROR : MYSQL ERROR       :  : 1419 : You do not have the SUPER privilege and binary logging is enabled (you *might* want to use the less safe log_bin_trust_function_creators variable)PHP message: WARN  : GENERIC_SQL       : Command Failed : CREATE TRIGGER tag_remove_trigger AFTER DELETE ON Tag
2021/01/30 22:16:00 [error] 23#23: *7 FastCGI sent in stderr: "				END IF;
						END;PHP message: ERROR : MYSQL ERROR       :  : 1419 : You do not have the SUPER privilege and binary logging is enabled (you *might* want to use the less safe log_bin_trust_function_creators variable)PHP message: WARN  : GENERIC_SQL       : Command Failed : CREATE TRIGGER syncinserttrigger BEFORE INSERT ON Playcounttable
						FOR EACH ROW
						BEGIN
							SET NEW.SyncCount = 1;
						END;PHP message: ERROR : MYSQL ERROR       :  : 1419 : You do not have the SUPER privilege and binary logging is enabled (you *might* want to use the less safe log_bin_trust_function_creators variable)PHP message: SHOUT : INIT              : Making Directory prefs/imagecachePHP message: SHOUT : INIT              : Making Directory prefs/podcastsPHP message: SHOUT : INIT              : Making Directory prefs/jsoncachePHP message: SHOUT : INIT              : Making Directory prefs/jsoncache/allmusicPHP message: SHOUT : INIT              : Making Directory prefs/jsoncache/discogsPHP message: SHOUT : INIT              : Making Directory prefs/jsoncache/musicbrainzPHP message: SHOUT : INIT              : Making Directory prefs/jsoncache/wikipediaPHP message: SHOUT : INIT              : Making Directory prefs/jsoncache/lastfmPHP message: SHOUT : INIT              : Making Directory prefs/jsoncache/spotifyPHP message: SHOUT : INIT              : Making Directory prefs/jsoncache/lyricsPHP message: SHOUT : INIT              : Making Directory prefs/jsoncache/googlePHP message: SHOUT : INIT              : Making Directory prefs/jsoncache/soundcloudPHP message: SHOUT : INIT              : Making Directory prefs/userplaylistsPHP message: SHOUT : INIT              : Making Directory prefs/plimagesPHP message: SHOUT : INIT              : Making Directory prefs/userbackgroundsPHP message: SHOUT : INIT              : Making Directory prefs/crazyplaylistsPHP message: SHOUT : INIT              : Making Directory prefs/databackupsPHP message: SHOUT : INIT        
2021/01/30 22:16:00 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: MARK  : INIT              : Including Skin Requirement css/jquery.mCustomScrollbar.cssPHP message: MARK  : INIT              : Including Skin Requirement css/tipTip.cssPHP message: MARK  : INIT              : Reconfiguring the Forward Deflector ArrayPHP message: LOG   : INIT              : Loading jquery/jquery-3.3.1.min.js" while reading upstream, client: <myclientIP>, server: _, request: "POST /index.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:00 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: LOG   : INIT              : Loading jquery/jquery-migrate-3.0.1.jsPHP message: LOG   : INIT              : Loading ui/functions.jsPHP message: LOG   : INIT              : Loading ui/prefs.jsPHP message: LOG   : INIT              : Loading ui/language.jsPHP message: LOG   : INIT              : Loading jquery/jquery-ui.min-19.1.18.jsPHP message: LOG   : INIT              : Loading jshash-2.2/md5-min.jsPHP message: LOG   : INIT              : Loading jquery/imagesloaded.pkgd.min.jsPHP message: LOG   : INIT              : Loading jquery/masonry.pkgd.min.jsPHP message: LOG   : INIT              : Loading includes/globals.jsPHP message: LOG   : INIT              : Loading ui/widgets.jsPHP message: LOG   : INIT              : Loading ui/uihelper.jsPHP message: LOG   : INIT              : Loading skins/desktop/skin.jsPHP message: LOG   : INIT              : Loading player/mpd/controller.jsPHP message: LOG   : INIT              : Loading ui/collectionhelper.jsPHP message: LOG   : INIT              : Loading player/player.jsPHP message: LOG   : INIT              : Loading ui/playlist.jsPHP message: LOG   : INIT              : Loading ui/readyhandlers.jsPHP message: LOG   : INIT              : Loading ui/debug.jsPHP message: LOG   : INIT              : Loading ui/uifunctions.jsPHP message: LOG   : INIT              : Loading ui/metahandlers.jsPHP message: LOG   : INIT              : Loading ui/clickfunctions.jsPHP message: LOG   : INIT              : Loading ui/lastfm.jsPHP message: LOG   : INIT              : Loading ui/nowplaying.jsPHP message: LOG   : INIT              : Loading ui/infobar2.jsPHP message: LOG   : INIT              : Loading ui/coverscraper.jsPHP message: LOG   : INIT              : Loading ui/favefinder.jsPHP message: LOG   : INIT              : Loading ui/podcasts.jsPHP message: LOG   : INIT              : Loading browser/info.jsPHP message: LOG   : INIT              : Loading snapcast/snapcast.jsPHP message: LOG   : INIT      
2021/01/30 22:16:00 [error] 23#23: *7 FastCGI sent in stderr: "ile.jsPHP message: LOG   : INIT              : Including Info Panel Plugin browser/plugins/02_info_lastfm.jsPHP message: LOG   : INIT              : Including Info Panel Plugin browser/plugins/03_info_wikipedia.jsPHP message: LOG   : INIT              : In" while reading upstream, client: <myclientIP>, server: _, request: "POST /index.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:00 [error] 23#23: *7 FastCGI sent in stderr: "cluding Info Panel Plugin browser/plugins/04_info_musicbrainz.jsPHP message: LOG   : INIT              : Including Info Panel Plugin browser/plugins/05_info_discogs.js" while reading upstream, client: <myclientIP>, server: _, request: "POST /index.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:00 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: LOG   : INIT              : Including Info Panel Plugin browser/plugins/06_info_lyrics.jsPHP message: LOG   : INIT              : Including Info Panel Plugin browser/plugins/07_info_soundcloud.jsPHP message: LOG   : INIT              : Including Info Panel Plugin browser/plugins/08_info_spotify.jsPHP message: LOG   : INIT              : Including Info Panel Plugin browser/plugins/09_info_videos.jsPHP message: LOG   : INIT              : Including Info Panel Plugin browser/plugins/info_ratings.jsPHP message: LOG   : INIT              : Including Smart Radio Plugin radios/00_builtins.jsPHP message: LOG   : INIT              : Including Smart Radio Plugin radios/musicfromeverywhere.jsPHP message: LOG   : INIT              : Including Smart Radio Plugin radios/musicfromspotify.jsPHP message: LOG   : INIT              : Including Smart Radio Plugin radios/spotifyplaylistgenerator.jsPHP message: LOG   : INIT              : Including Plugin plugins/alarmclock.jsPHP message: LOG   : INIT              : Including Plugin plugins/dynamic.jsPHP message: LOG   : INIT              : Including Plugin plugins/sleeptimer.jsPHP message: LOG   : INIT              : Including Plugin plugins/zzz-aboutrompr.jsPHP message: LOG   : INIT              : Including Plugin plugins/zzzz-debuginfo.jsPHP message: LOG   : INIT              : Including Skin Requirement jquery/jquery.hotkeys.jsPHP message: LOG   : INIT              : Including Skin Requirement jquery/jquery.mCustomScrollbar.concat.min-3.1.5.jsPHP message: LOG   : INIT              : Including Skin Requirement ui/hotkeys.jsPHP message: LOG   : LAYOUT            : Including skins/desktop/skin.phpPHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading upstream, client: <myclientIP>, server: _, request: "POST /index.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:00 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: SHOUT : INIT FINISHED     : ******++++++======------******------======++++++******" while reading upstream, client: <myclientIP>, server: _, request: "POST /index.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:01 [error] 21#21: *15 FastCGI sent in stderr: "PHP message: LOG   : THEME             : Theme       : Numismatist.cssPHP message: LOG   : THEME             : Font        : Nunito.cssPHP message: LOG   : THEME             : Font Size   : 04-Grande.cssPHP message: LOG   : THEME             : Cover Size  : 40-Large.cssPHP message: LOG   : THEME             : Icons       : Modern-Dark" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /gettheme.php?version=1612044956384&theme=Numismatist.css&fontsize=04-Grande.css&fontfamily=Nunito.css&coversize=40-Large.css&icontheme=Modern-Dark HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:02 [error] 22#22: *16 FastCGI sent in stderr: "PHP message: TRACE : COLLECTION        : Collection type from database is not setPHP message: TRACE : COLLECTION        : Prefs collection_player is currently PHP message: LOG   : BACKIMAGE         : getbackground = Numismatist.cssPHP message: LOG   : BACKIMAGE         : browser_id = nullPHP message: LOG   : BACKIMAGE         : No Custom Backgrounds Exist for Numismatist.css null" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /backimage.php?getbackground=Numismatist.css&browser_id=null HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:02 [error] 21#21: *14 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /player/mpd/geturlhandlers.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:02 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : GETLOCATION       : Requesting IP address location lookupPHP message: TRACE : URL_DOWNLOADER    : Downloading https://ipinfo.io?token=230de83c74e3f3PHP message: TRACE : URL_DOWNLOADER    :   ..  Download SuccessPHP message: LOG   : GETLOCATION       : Got response from IP location lookup. Country is DE" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /utils/getgeoip.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:02 [error] 22#22: *16 FastCGI sent in stderr: "PHP message: LOG   : SAVEPREFS         : Saving prefsPHP message: LOG   : SAVEPREFS         :   test_width = 1430PHP message: LOG   : SAVEPREFS         :   test_height = 642" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /saveprefs.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:02 [error] 21#21: *15 FastCGI sent in stderr: "PHP message: LOG   : SAVEPREFS         : Saving prefsPHP message: LOG   : SAVEPREFS         :   linkchecker_nextrun = 1612649756823PHP message: LOG   : SAVEPREFS         :   linkchecker_isrunning = 1" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /saveprefs.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:02 [error] 22#22: *16 FastCGI sent in stderr: "PHP message: BLURT : USERRATING        : --------------------------START---------------------PHP message: TRACE : COLLECTION        : Collection type from database is not setPHP message: TRACE : COLLECTION        : Prefs collection_player is currently PHP message: MARK  : USERRATING        : Doing action RESETLINKCHECKPHP message: LOG   :   Parameter       : imagekey : d41d8cd98f00b204e9800998ecf8427ePHP message: LOG   :   Parameter       : disc : 1PHP message: LOG   :   Parameter       : domain : localPHP message: BLURT : USERRATING        : ---------------------------END----------------------" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /backends/sql/userRatings.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:02 [error] 22#22: *16 FastCGI sent in stderr: "PHP message: LOG   : SAVEPREFS         : Saving prefsPHP message: LOG   : SAVEPREFS         :   lastfm_country_code = DEPHP message: LOG   : SAVEPREFS         :   country_userset = 1" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /saveprefs.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:02 [error] 22#22: *16 FastCGI sent in stderr: "PHP message: TRACE : COLLECTION        : Collection type from database is not setPHP message: TRACE : COLLECTION        : Prefs collection_player is currently PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:02 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : COLLECTION        : Collection type from database is not setPHP message: TRACE : COLLECTION        : Prefs collection_player is currently PHP message: TRACE : TIMINGS           : ======================================================================PHP message: TRACE : COLLECTION        : Memory Used is 554224PHP message: LOG   : COLLECTION        : Request is item = aartistrootPHP message: TRACE : DUMPALBUMS        : Generating artist aartistroot from databasePHP message: TRACE : TIMINGS           : == Collection Update And Send took 00:00PHP message: TRACE : TIMINGS           : Peak Memory Used Was 613,792 bytes  - meaning we used 59,568 bytes.PHP message: TRACE : TIMINGS           : ======================================================================" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /albums.php?item=aartistroot&_=1612044955428 HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:02 [error] 21#21: *15 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600PHP message: MARK  : DIRBROWSER        : Browsing" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /dirbrowser.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:02 [error] 21#21: *15 FastCGI sent in stderr: "PHP message: TRACE : COLLECTION        : Collection type from database is not setPHP message: TRACE : COLLECTION        : Prefs collection_player is currently PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /player/mpd/loadplaylists.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:02 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : COLLECTION        : Collection type from database is not setPHP message: TRACE : COLLECTION        : Prefs collection_player is currently PHP message: TRACE : TIMINGS           : ======================================================================PHP message: TRACE : COLLECTION        : Memory Used is 552928PHP message: LOG   : COLLECTION        : Request is item = zartistrootPHP message: TRACE : DUMPALBUMS        : Generating artist zartistroot from databasePHP message: TRACE : TIMINGS           : == Collection Update And Send took 00:00PHP message: TRACE : TIMINGS           : Peak Memory Used Was 572,896 bytes  - meaning we used 19,968 bytes.PHP message: TRACE : TIMINGS           : ======================================================================" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /albums.php?item=zartistroot HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:02 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : COLLECTION        : Collection type from database is not setPHP message: TRACE : COLLECTION        : Prefs collection_player is currently PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /player/mpd/loadplaylists.php?addtoplaylistmenu HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:07 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: BLURT : USERRATING        : --------------------------START---------------------PHP message: TRACE : COLLECTION        : Collection type from database is not setPHP message: TRACE : COLLECTION        : Prefs collection_player is currently PHP message: MARK  : USERRATING        : Doing action GETLINKTOCHECKPHP message: LOG   :   Parameter       : imagekey : d41d8cd98f00b204e9800998ecf8427ePHP message: LOG   :   Parameter       : disc : 1PHP message: LOG   :   Parameter       : domain : localPHP message: LOG   : USERRATINGS       : Preparing Return InfoPHP message: BLURT : USERRATING        : ---------------------------END----------------------" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /backends/sql/userRatings.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:07 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: LOG   : SAVEPREFS         : Saving prefsPHP message: LOG   : SAVEPREFS         :   linkchecker_isrunning =" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /saveprefs.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:11 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : COLLECTION        : Collection type from database is not setPHP message: TRACE : COLLECTION        : Prefs collection_player is currently PHP message: LOG   : PODCASTS          : Next update is required in 2119200 seconds" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /includes/podcasts.php?populate=1&checkrefresh=1 HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:11 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : COLLECTION        : Collection type from database is not setPHP message: TRACE : COLLECTION        : Prefs collection_player is currently" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /includes/podcasts.php?populate=1&getcounts=1 HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:18 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : COLLECTION        : Collection type from database is not setPHP message: TRACE : COLLECTION        : Prefs collection_player is currently PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600PHP message: TRACE : POSTCOMMAND       : RAW command : updatePHP message: TRACE : POSTCOMMAND       : Command : updatePHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: ERROR : MPD               : Failure to fput command statusPHP message: LOG   : MPD               : Clearing Player Error There was an error communicating with Mopidy! (could not write to socket)PHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - Retrying" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:18 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: LOG   : REDIRECT          : Uri is /newimages/thisdosntexist.png" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /newimages/thisdosntexist.png HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:20 [error] 21#21: *15 FastCGI sent in stderr: "PHP message: TRACE : COLLECTION        : Collection type from database is not setPHP message: TRACE : COLLECTION        : Prefs collection_player is currently PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:20 [error] 21#21: *15 FastCGI sent in stderr: "PHP message: TRACE : COLLECTION        : Collection type from database is not setPHP message: TRACE : COLLECTION        : Prefs collection_player is currently PHP message: TRACE : TIMINGS           : ======================================================================PHP message: TRACE : COLLECTION        : Memory Used is 553088PHP message: LOG   : COLLECTION        : Request is rebuild = yesPHP message: WARN  : COLLECTION        : Got null response to update lock checkPHP message: LOG   : COLLECTION        : SAPI Name is fpm-fcgiPHP message: MARK  : COLLECTION        : Closing Request The FastCGI Way" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /albums.php?rebuild=yes&_=1612044955429 HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:21 [error] 22#22: *16 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600PHP message: MARK  : DIRBROWSER        : Browsing" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /dirbrowser.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:24 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : COLLECTION        : Collection type from database is not setPHP message: TRACE : COLLECTION        : Prefs collection_player is currently PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600PHP message: TRACE : POSTCOMMAND       : RAW command : consume PHP message: TRACE : POSTCOMMAND       : Command : consume ""PHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: ERROR : MPD               : Failure to fput command statusPHP message: LOG   : MPD               : Clearing Player Error There was an error communicating with Mopidy! (could not write to socket)PHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - Retrying" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:30 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : TIMINGS           : ======================================================================PHP message: TRACE : COLLECTION        : Memory Used is 553152PHP message: LOG   : COLLECTION        : Request is item = aartistrootPHP message: TRACE : DUMPALBUMS        : Generating artist aartistroot from databasePHP message: TRACE : TIMINGS           : == Collection Update And Send took 00:00PHP message: TRACE : TIMINGS           : Peak Memory Used Was 573,120 bytes  - meaning we used 19,968 bytes.PHP message: TRACE : TIMINGS           : ======================================================================" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /albums.php?item=aartistroot&_=1612044955430 HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:30 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : TIMINGS           : ======================================================================PHP message: TRACE : COLLECTION        : Memory Used is 552928PHP message: LOG   : COLLECTION        : Request is item = zartistrootPHP message: TRACE : DUMPALBUMS        : Generating artist zartistroot from databasePHP message: TRACE : TIMINGS           : == Collection Update And Send took 00:00PHP message: TRACE : TIMINGS           : Peak Memory Used Was 572,896 bytes  - meaning we used 19,968 bytes.PHP message: TRACE : TIMINGS           : ======================================================================" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /albums.php?item=zartistroot HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:34 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:44 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:16:54 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:17:04 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:17:14 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:17:24 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:17:35 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:17:46 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:17:57 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:18:08 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:18:19 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:18:30 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:18:41 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:18:52 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:19:03 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:19:14 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:19:25 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:19:36 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:19:47 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:19:58 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:20:09 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:20:20 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:20:31 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/30 22:20:42 [error] 23#23: *7 FastCGI sent in stderr: "PHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"

I am quite new to the topic and a bit overwhelmed by that file. I assume it's some kind of connectivity/access right problem I have but don't have a clue how to overcome it. I also looked at the PHP variables as described in the rawdlite-description, but don't really know what to look for.
The CIFS-share the small test-collection (only 12 files, correctly tagged) is stored on is open for all users.

I would appreciate any help since I am still not really sure whether it's a RompR, mopidy or docker issue (or all together).

Cheers

  • solved #5
  • replies 9
  • views 250
  • likes 0
#2

Binary logging is enabled on your mysql installation. You have missed a atep in the setup. Using sqlite is generally better anyway.

nGaDOLEr · · author
#3

Thanks for clarifying, and the immediate response. I went without the mysql-instance and deployed the stacks using sqlite again.
Still got a similar failure (first setup "There was an error communicating with Mopidy! (could not write to socket)", second hit on OK got me to the GUI. Again, update collection didn't get me a failure message but no entries either.

I also tried to play radio and see the stations, but when I try to play one I get "Failed sending commands to mopidy".

Logged the errors again (level 6 this time) and got the following:

2021/01/31 11:33:13 [error] 20#20: *1 FastCGI sent in stderr: "PHP message: LOG   : INIT              : ******++++++======------******------======++++++******PHP message: BLURT : INIT              : Detecting window size to decide which skin to use" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET / HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050"
2021/01/31 11:33:13 [error] 20#20: *1 FastCGI sent in stderr: "PHP message: LOG   : INIT              : ******++++++======------******------======++++++******PHP message: LOG   : INIT              : Loading Skin Requirements FilePHP message: MARK  : INIT              : Attempting to connect to player DefaultPHP message: MARK  : INIT              : Player backend cookie is not setPHP message: TRACE : MPDPLAYER         : Creating Player for localhost:6600PHP message: ERROR : INIT              : MPD Connection FailurePHP message: FAIL  : INIT              : MPD Connection FailedPHP message: LOG   : SETUP             : Displaying Setup Screen" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET / HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/"
2021/01/31 11:33:13 [error] 20#20: *5 FastCGI sent in stderr: "PHP message: LOG   : THEME             : Theme       : Numismatist.cssPHP message: LOG   : THEME             : Font        : Nunito.cssPHP message: LOG   : THEME             : Font Size   : 02-Normal.cssPHP message: LOG   : THEME             : Cover Size  : 40-Large.cssPHP message: LOG   : THEME             : Icons       : New-Dark-Circled" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /gettheme.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/"
2021/01/31 11:34:20 [error] 21#21: *7 FastCGI sent in stderr: "PHP message: LOG   : INIT              : ******++++++======------******------======++++++******PHP message: LOG   : INIT              : Loading Skin Requirements FilePHP message: MARK  : INIT              : Setting Pref currenthost to DefaultPHP message: MARK  : INIT              : Setting Pref mpd_host to <myIP>PHP message: MARK  : INIT              : Setting Pref mpd_port to 6600PHP message: MARK  : INIT              : Setting Pref mpd_password to PHP message: MARK  : INIT              : Setting Pref unix_socket to PHP message: MARK  : INIT              : Setting Pref cleanalbumimages to 1PHP message: MARK  : INIT              : Setting Pref google_api_key to PHP message: MARK  : INIT              : Setting Pref google_search_engine_id to PHP message: MARK  : INIT              : Setting Pref collection_type to sqlitePHP message: MARK  : INIT              : Setting Pref mysql_host to PHP message: MARK  : INIT              : Setting Pref mysql_port to PHP message: MARK  : INIT              : Setting Pref mysql_database to PHP message: MARK  : INIT              : Setting Pref mysql_user to PHP message: MARK  : INIT              : Setting Pref mysql_password to PHP message: MARK  : INIT              : Setting Pref proxy_host to PHP message: MARK  : INIT              : Setting Pref proxy_user to PHP message: MARK  : INIT              : Setting Pref proxy_password to PHP message: MARK  : INIT              : Setting Pref debug_enabled to 6PHP message: MARK  : INIT              : Setting Pref custom_logfile to PHP message: MARK  : INIT              : Setting Pref do_not_show_prefs to PHP message: MARK  : INIT              : Setting Pref separate_collections to PHP message: MARK  : INIT              : Attempting to connect to player DefaultPHP message: MARK  : INIT              : Player backend cookie is not setPHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600PHP message: SHOUT : MPDPLAYER         : Probing Player Type
2021/01/31 11:34:26 [error] 21#21: *7 FastCGI sent in stderr: "PHP message: MARK  : INIT              : Setting Pref currenthost to DefaultPHP message: MARK  : INIT              : Setting Pref mpd_host to <myIP>PHP message: MARK  : INIT              : Setting Pref mpd_port to 6600PHP message: MARK  : INIT              : Setting Pref mpd_password to PHP message: MARK  : INIT              : Setting Pref unix_socket to PHP message: MARK  : INIT              : Setting Pref cleanalbumimages to 1PHP message: MARK  : INIT              : Setting Pref google_api_key to PHP message: MARK  : INIT              : Setting Pref google_search_engine_id to PHP message: MARK  : INIT              : Setting Pref collection_type to sqlitePHP message: MARK  : INIT              : Setting Pref mysql_host to PHP message: MARK  : INIT              : Setting Pref mysql_port to PHP message: MARK  : INIT              : Setting Pref mysql_database to PHP message: MARK  : INIT              : Setting Pref mysql_user to PHP message: MARK  : INIT              : Setting Pref mysql_password to PHP message: MARK  : INIT              : Setting Pref proxy_host to PHP message: MARK  : INIT              : Setting Pref proxy_user to PHP message: MARK  : INIT              : Setting Pref proxy_password to PHP message: MARK  : INIT              : Setting Pref debug_enabled to 6PHP message: MARK  : INIT              : Setting Pref custom_logfile to PHP message: MARK  : INIT              : Setting Pref do_not_show_prefs to PHP message: MARK  : INIT              : Setting Pref separate_collections to PHP message: MARK  : INIT              : Attempting to connect to player DefaultPHP message: MARK  : INIT              : Player backend cookie is mopidyPHP message: ERROR : MYSQL ERROR       :  : 1 : no such table: StatstablePHP message: SHOUT : INIT              : Making Directory prefs/imagecachePHP message: SHOUT : INIT              : Making Directory prefs/podcastsPHP message: SHOUT : INIT              : Making Directory prefs/jsoncachePHP message: SH
2021/01/31 11:34:26 [error] 21#21: *7 FastCGI sent in stderr: "PHP message: MARK  : INIT              : Including Skin Requirement css/jquery.mCustomScrollbar.cssPHP message: MARK  : INIT              : Including Skin Requirement css/tipTip.cssPHP message: MARK  : INIT              : Reconfiguring the Forward Deflector Array" while reading upstream, client: <myclientIP>, server: _, request: "POST /index.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 11:34:26 [error] 21#21: *7 FastCGI sent in stderr: "PHP message: SHOUT : MYSQL             : Collection has not been built0" while reading upstream, client: <myclientIP>, server: _, request: "POST /index.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 11:34:26 [error] 21#21: *7 FastCGI sent in stderr: "PHP message: SHOUT : INIT FINISHED     : ******++++++======------******------======++++++******" while reading upstream, client: <myclientIP>, server: _, request: "POST /index.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 11:34:28 [error] 21#21: *14 FastCGI sent in stderr: "PHP message: BLURT : USERRATING        : --------------------------START---------------------PHP message: MARK  : USERRATING        : Doing action RESETLINKCHECKPHP message: BLURT : USERRATING        : ---------------------------END----------------------" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /backends/sql/userRatings.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 11:34:29 [error] 20#20: *12 FastCGI sent in stderr: "PHP message: MARK  : DIRBROWSER        : Browsing" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /dirbrowser.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 11:34:33 [error] 21#21: *7 FastCGI sent in stderr: "PHP message: BLURT : USERRATING        : --------------------------START---------------------PHP message: MARK  : USERRATING        : Doing action GETLINKTOCHECKPHP message: BLURT : USERRATING        : ---------------------------END----------------------" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /backends/sql/userRatings.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 11:34:41 [error] 21#21: *7 FastCGI sent in stderr: "PHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: ERROR : MPD               : Failure to fput command statusPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - Retrying" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 11:34:43 [error] 21#21: *14 FastCGI sent in stderr: "PHP message: WARN  : COLLECTION        : Got null response to update lock checkPHP message: MARK  : COLLECTION        : Closing Request The FastCGI Way" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /albums.php?rebuild=yes&_=1612092861366 HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 11:34:43 [error] 20#20: *13 FastCGI sent in stderr: "PHP message: MARK  : DIRBROWSER        : Browsing" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /dirbrowser.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 11:34:47 [error] 21#21: *7 FastCGI sent in stderr: "PHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: ERROR : MPD               : Failure to fput command statusPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - Retrying" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 11:42:11 [error] 21#21: *7 FastCGI sent in stderr: "PHP message: MARK  : INIT              : Setting Pref currenthost to DefaultPHP message: MARK  : INIT              : Setting Pref mpd_host to <myIP>PHP message: MARK  : INIT              : Setting Pref mpd_port to 6600PHP message: MARK  : INIT              : Setting Pref mpd_password to PHP message: MARK  : INIT              : Setting Pref unix_socket to PHP message: MARK  : INIT              : Setting Pref cleanalbumimages to 1PHP message: MARK  : INIT              : Setting Pref google_api_key to PHP message: MARK  : INIT              : Setting Pref google_search_engine_id to PHP message: MARK  : INIT              : Setting Pref collection_type to sqlitePHP message: MARK  : INIT              : Setting Pref mysql_host to localhostPHP message: MARK  : INIT              : Setting Pref mysql_port to 3306PHP message: MARK  : INIT              : Setting Pref mysql_database to romprdbPHP message: MARK  : INIT              : Setting Pref mysql_user to romprPHP message: MARK  : INIT              : Setting Pref mysql_password to romprdbpassPHP message: MARK  : INIT              : Setting Pref proxy_host to PHP message: MARK  : INIT              : Setting Pref proxy_user to PHP message: MARK  : INIT              : Setting Pref proxy_password to PHP message: MARK  : INIT              : Setting Pref debug_enabled to 6PHP message: MARK  : INIT              : Setting Pref custom_logfile to PHP message: MARK  : INIT              : Setting Pref do_not_show_prefs to PHP message: MARK  : INIT              : Setting Pref separate_collections to PHP message: MARK  : INIT              : Attempting to connect to player DefaultPHP message: MARK  : INIT              : Player backend cookie is mopidyPHP message: SHOUT : CREATING PAGE     : ******++++++======------******------======++++++******" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /index.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:",
2021/01/31 11:42:11 [error] 21#21: *7 FastCGI sent in stderr: "PHP message: MARK  : INIT              : Including Skin Requirement css/jquery.mCustomScrollbar.cssPHP message: MARK  : INIT              : Including Skin Requirement css/tipTip.cssPHP message: MARK  : INIT              : Reconfiguring the Forward Deflector Array" while reading upstream, client: <myclientIP>, server: _, request: "POST /index.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/?setup"
2021/01/31 11:42:11 [error] 21#21: *7 FastCGI sent in stderr: "PHP message: SHOUT : INIT FINISHED     : ******++++++======------******------======++++++******" while reading upstream, client: <myclientIP>, server: _, request: "POST /index.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/?setup"
2021/01/31 11:42:13 [error] 20#20: *92 FastCGI sent in stderr: "PHP message: MARK  : DIRBROWSER        : Browsing" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /dirbrowser.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 11:42:20 [error] 20#20: *92 FastCGI sent in stderr: "PHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: ERROR : MPD               : Failure to fput command statusPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - Retrying" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 11:42:22 [error] 20#20: *96 FastCGI sent in stderr: "PHP message: MARK  : DIRBROWSER        : Browsing" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /dirbrowser.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 11:42:22 [error] 21#21: *7 FastCGI sent in stderr: "PHP message: WARN  : COLLECTION        : Got null response to update lock checkPHP message: MARK  : COLLECTION        : Closing Request The FastCGI Way" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /albums.php?rebuild=yes&_=1612093326462 HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 11:42:26 [error] 20#20: *92 FastCGI sent in stderr: "PHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: ERROR : MPD               : Failure to fput command statusPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - Retrying" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 11:53:30 [error] 20#20: *7 FastCGI sent in stderr: "PHP message: BLURT : INIT              : Detecting window size to decide which skin to use" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET / HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050"
2021/01/31 11:53:33 [error] 20#20: *11 FastCGI sent in stderr: "PHP message: MARK  : INIT              : Attempting to connect to player DefaultPHP message: MARK  : INIT              : Player backend cookie is not setPHP message: SHOUT : MPDPLAYER         : Probing Player Type....PHP message: MARK  : MPDPLAYER         : WARNING! No output for 'tagtypes' - probably an old version of Mopidy. RompЯ may not function correctlyPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: ERROR : MPD               : Failure to fput command statusPHP message: FAIL  : INIT              : MPD Password Failed or other status failurePHP message: FAIL  : INIT              : MPD Connection Failed" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET / HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050"
2021/01/31 11:53:41 [error] 20#20: *16 FastCGI sent in stderr: "PHP message: MARK  : INIT              : Setting Pref currenthost to DefaultPHP message: MARK  : INIT              : Setting Pref mpd_host to <myIP>PHP message: MARK  : INIT              : Setting Pref mpd_port to 6600PHP message: MARK  : INIT              : Setting Pref mpd_password to PHP message: MARK  : INIT              : Setting Pref unix_socket to PHP message: MARK  : INIT              : Setting Pref cleanalbumimages to 1PHP message: MARK  : INIT              : Setting Pref google_api_key to PHP message: MARK  : INIT              : Setting Pref google_search_engine_id to PHP message: MARK  : INIT              : Setting Pref collection_type to sqlitePHP message: MARK  : INIT              : Setting Pref mysql_host to localhostPHP message: MARK  : INIT              : Setting Pref mysql_port to 3306PHP message: MARK  : INIT              : Setting Pref mysql_database to romprdbPHP message: MARK  : INIT              : Setting Pref mysql_user to romprPHP message: MARK  : INIT              : Setting Pref mysql_password to romprdbpassPHP message: MARK  : INIT              : Setting Pref proxy_host to PHP message: MARK  : INIT              : Setting Pref proxy_user to PHP message: MARK  : INIT              : Setting Pref proxy_password to PHP message: MARK  : INIT              : Setting Pref debug_enabled to 6PHP message: MARK  : INIT              : Setting Pref custom_logfile to PHP message: MARK  : INIT              : Setting Pref do_not_show_prefs to PHP message: MARK  : INIT              : Setting Pref separate_collections to PHP message: MARK  : INIT              : Attempting to connect to player DefaultPHP message: MARK  : INIT              : Player backend cookie is mopidyPHP message: SHOUT : CREATING PAGE     : ******++++++======------******------======++++++******" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /index.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:"
2021/01/31 11:53:41 [error] 20#20: *16 FastCGI sent in stderr: "PHP message: MARK  : INIT              : Including Skin Requirement css/jquery.mCustomScrollbar.cssPHP message: MARK  : INIT              : Including Skin Requirement css/tipTip.cssPHP message: MARK  : INIT              : Reconfiguring the Forward Deflector Array" while reading upstream, client: <myclientIP>, server: _, request: "POST /index.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/"
2021/01/31 11:53:41 [error] 20#20: *16 FastCGI sent in stderr: "PHP message: SHOUT : INIT FINISHED     : ******++++++======------******------======++++++******" while reading upstream, client: <myclientIP>, server: _, request: "POST /index.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/"
2021/01/31 11:53:44 [error] 20#20: *14 FastCGI sent in stderr: "PHP message: MARK  : DIRBROWSER        : Browsing" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /dirbrowser.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 11:53:56 [error] 20#20: *16 FastCGI sent in stderr: "PHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: ERROR : MPD               : Failure to fput command statusPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - Retrying" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 11:53:59 [error] 20#20: *14 FastCGI sent in stderr: "PHP message: MARK  : DIRBROWSER        : Browsing" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /dirbrowser.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 11:53:59 [error] 20#20: *13 FastCGI sent in stderr: "PHP message: WARN  : COLLECTION        : Got null response to update lock checkPHP message: MARK  : COLLECTION        : Closing Request The FastCGI Way" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /albums.php?rebuild=yes&_=1612094016460 HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 11:54:02 [error] 20#20: *16 FastCGI sent in stderr: "PHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: ERROR : MPD               : Failure to fput command statusPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - Retrying" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 11:54:38 [error] 20#20: *13 FastCGI sent in stderr: "PHP message: SHOUT : RADIO             : Adding New Radio StationPHP message: MARK  : RADIO             :   Name  : Seven Inch SoulPHP message: MARK  : RADIO             :   Image : getRemoteImage.php?url=https://api.somafm.com/logos/512/7soul512.pngPHP message: MARK  : RADIO             :   URL   : http://somafm.com/7soul.plsPHP message: MARK  : RADIO             :   Adding New Track http://somafm.com/7soul.pls to station 1PHP message: PHP Notice:  fputs(): send of 34 bytes failed with errno=32 Broken pipe in /app/rompr/player/mpd/mpdinterface.php on line 238PHP message: PHP Notice:  fputs(): send of 9 bytes failed with errno=32 Broken pipe in /app/rompr/player/mpd/mpdinterface.php on line 238PHP message: WARN  : MPD               : Socket Write Error for command_list_end - RetryingPHP message: WARN  : MPD               : Socket Write Error for command_list_end - RetryingPHP message: WARN  : MPD               : Socket Write Error for command_list_end - RetryingPHP message: WARN  : MPD               : Socket Write Error for command_list_end - RetryingPHP message: WARN  : MPD               : Socket Write Error for command_list_end - RetryingPHP message: ERROR : MPD               : Failure to fput command command_list_endPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: ERROR : MPD               : Failure to fput command statusPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD

Anything else I miss? Or is the binary logging to be disabled in sqlite as well?

Cheers

nGaDOLEr · · author
#4

Figured out that sqlite3 wasn't installed on my device. Did this, didn't fix the problem.
New errorlog:

2021/01/31 12:24:03 [error] 23#23: *2 FastCGI sent in stderr: "PHP message: LOG   : INIT              : ******++++++======------******------======++++++******PHP message: BLURT : INIT              : Detecting window size to decide which skin to use" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET / HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050"
2021/01/31 12:24:03 [error] 23#23: *2 FastCGI sent in stderr: "PHP message: LOG   : INIT              : ******++++++======------******------======++++++******PHP message: LOG   : INIT              : Loading Skin Requirements FilePHP message: MARK  : INIT              : Attempting to connect to player DefaultPHP message: MARK  : INIT              : Player backend cookie is not setPHP message: TRACE : MPDPLAYER         : Creating Player for localhost:6600PHP message: ERROR : INIT              : MPD Connection FailurePHP message: FAIL  : INIT              : MPD Connection FailedPHP message: LOG   : SETUP             : Displaying Setup Screen" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET / HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/"
2021/01/31 12:24:04 [error] 21#21: *5 FastCGI sent in stderr: "PHP message: LOG   : THEME             : Theme       : Numismatist.cssPHP message: LOG   : THEME             : Font        : Nunito.cssPHP message: LOG   : THEME             : Font Size   : 02-Normal.cssPHP message: LOG   : THEME             : Cover Size  : 40-Large.cssPHP message: LOG   : THEME             : Icons       : New-Dark-Circled" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /gettheme.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/"
2021/01/31 12:24:19 [error] 21#21: *5 FastCGI sent in stderr: "PHP message: LOG   : INIT              : ******++++++======------******------======++++++******PHP message: LOG   : INIT              : Loading Skin Requirements FilePHP message: MARK  : INIT              : Setting Pref currenthost to DefaultPHP message: MARK  : INIT              : Setting Pref mpd_host to <myIP>PHP message: MARK  : INIT              : Setting Pref mpd_port to 6600PHP message: MARK  : INIT              : Setting Pref mpd_password to PHP message: MARK  : INIT              : Setting Pref unix_socket to PHP message: MARK  : INIT              : Setting Pref cleanalbumimages to 1PHP message: MARK  : INIT              : Setting Pref google_api_key to PHP message: MARK  : INIT              : Setting Pref google_search_engine_id to PHP message: MARK  : INIT              : Setting Pref collection_type to sqlitePHP message: MARK  : INIT              : Setting Pref mysql_host to localhostPHP message: MARK  : INIT              : Setting Pref mysql_port to 3306PHP message: MARK  : INIT              : Setting Pref mysql_database to romprdbPHP message: MARK  : INIT              : Setting Pref mysql_user to romprPHP message: MARK  : INIT              : Setting Pref mysql_password to romprdbpassPHP message: MARK  : INIT              : Setting Pref proxy_host to PHP message: MARK  : INIT              : Setting Pref proxy_user to PHP message: MARK  : INIT              : Setting Pref proxy_password to PHP message: MARK  : INIT              : Setting Pref debug_enabled to 6PHP message: MARK  : INIT              : Setting Pref custom_logfile to PHP message: MARK  : INIT              : Setting Pref do_not_show_prefs to PHP message: MARK  : INIT              : Setting Pref separate_collections to PHP message: MARK  : INIT              : Attempting to connect to player DefaultPHP message: MARK  : INIT              : Player backend cookie is not setPHP message: TRACE : MPDPLAYER         : Creating Player for <myIP>:6600PHP message: SHOUT : MPD
2021/01/31 12:24:25 [error] 21#21: *5 FastCGI sent in stderr: "PHP message: MARK  : INIT              : Setting Pref currenthost to DefaultPHP message: MARK  : INIT              : Setting Pref mpd_host to <myIP>PHP message: MARK  : INIT              : Setting Pref mpd_port to 6600PHP message: MARK  : INIT              : Setting Pref mpd_password to PHP message: MARK  : INIT              : Setting Pref unix_socket to PHP message: MARK  : INIT              : Setting Pref cleanalbumimages to 1PHP message: MARK  : INIT              : Setting Pref google_api_key to PHP message: MARK  : INIT              : Setting Pref google_search_engine_id to PHP message: MARK  : INIT              : Setting Pref collection_type to sqlitePHP message: MARK  : INIT              : Setting Pref mysql_host to localhostPHP message: MARK  : INIT              : Setting Pref mysql_port to 3306PHP message: MARK  : INIT              : Setting Pref mysql_database to romprdbPHP message: MARK  : INIT              : Setting Pref mysql_user to romprPHP message: MARK  : INIT              : Setting Pref mysql_password to romprdbpassPHP message: MARK  : INIT              : Setting Pref proxy_host to PHP message: MARK  : INIT              : Setting Pref proxy_user to PHP message: MARK  : INIT              : Setting Pref proxy_password to PHP message: MARK  : INIT              : Setting Pref debug_enabled to 6PHP message: MARK  : INIT              : Setting Pref custom_logfile to PHP message: MARK  : INIT              : Setting Pref do_not_show_prefs to PHP message: MARK  : INIT              : Setting Pref separate_collections to PHP message: MARK  : INIT              : Attempting to connect to player DefaultPHP message: MARK  : INIT              : Player backend cookie is mopidyPHP message: ERROR : MYSQL ERROR       :  : 1 : no such table: StatstablePHP message: SHOUT : INIT              : Making Directory prefs/imagecachePHP message: SHOUT : INIT              : Making Directory prefs/podcastsPHP message: SHOUT : INIT              : Making Dire
2021/01/31 12:24:25 [error] 21#21: *5 FastCGI sent in stderr: "PHP message: MARK  : INIT              : Including Skin Requirement css/jquery.mCustomScrollbar.cssPHP message: MARK  : INIT              : Including Skin Requirement css/tipTip.cssPHP message: MARK  : INIT              : Reconfiguring the Forward Deflector Array" while reading upstream, client: <myclientIP>, server: _, request: "POST /index.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 12:24:25 [error] 21#21: *5 FastCGI sent in stderr: "PHP message: SHOUT : MYSQL             : Collection has not been built0" while reading upstream, client: <myclientIP>, server: _, request: "POST /index.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 12:24:25 [error] 21#21: *5 FastCGI sent in stderr: "PHP message: SHOUT : INIT FINISHED     : ******++++++======------******------======++++++******" while reading upstream, client: <myclientIP>, server: _, request: "POST /index.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 12:24:27 [error] 21#21: *5 FastCGI sent in stderr: "PHP message: BLURT : USERRATING        : --------------------------START---------------------PHP message: MARK  : USERRATING        : Doing action RESETLINKCHECKPHP message: BLURT : USERRATING        : ---------------------------END----------------------" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /backends/sql/userRatings.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 12:24:28 [error] 21#21: *8 FastCGI sent in stderr: "PHP message: MARK  : DIRBROWSER        : Browsing" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /dirbrowser.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 12:24:32 [error] 21#21: *8 FastCGI sent in stderr: "PHP message: BLURT : USERRATING        : --------------------------START---------------------PHP message: MARK  : USERRATING        : Doing action GETLINKTOCHECKPHP message: BLURT : USERRATING        : ---------------------------END----------------------" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /backends/sql/userRatings.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 12:24:39 [error] 21#21: *8 FastCGI sent in stderr: "PHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: ERROR : MPD               : Failure to fput command statusPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - Retrying" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 12:24:41 [error] 23#23: *2 FastCGI sent in stderr: "PHP message: WARN  : COLLECTION        : Got null response to update lock checkPHP message: MARK  : COLLECTION        : Closing Request The FastCGI Way" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /albums.php?rebuild=yes&_=1612095860586 HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 12:24:42 [error] 21#21: *5 FastCGI sent in stderr: "PHP message: MARK  : DIRBROWSER        : Browsing" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /dirbrowser.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 12:24:45 [error] 21#21: *8 FastCGI sent in stderr: "PHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: ERROR : MPD               : Failure to fput command statusPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - RetryingPHP message: WARN  : MPD               : Socket Write Error for clearerror - Retrying" while reading response header from upstream, client: <myclientIP>, server: _, request: "POST /player/mpd/postcommand.php HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 12:29:24 [error] 21#21: *8 FastCGI sent in stderr: "PHP message: MARK  : TUNEIN            : Getting URL http://opml.radiotime.com/" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /streamplugins/03_tuneinradio.php?populate=2 HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 12:29:26 [error] 21#21: *8 FastCGI sent in stderr: "PHP message: MARK  : TUNEIN            : Getting URL http://opml.radiotime.com/Browse.ashx?c=local" while reading response header from upstream, client: <myclientIP>, server: _, request: "GET /streamplugins/03_tuneinradio.php?populate=2&url=http%3A%2F%2Fopml.radiotime.com%2FBrowse.ashx%3Fc%3Dlocal&title=Local%20Radio&target=tunein_36b5c50a7b805eee3c940dfdd1032efb HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.3-fpm.sock:", host: "<myIP>:8050", referrer: "http://<myIP>:8050/index.php"
2021/01/31 12:29:40 [error] 21#21: *8 FastCGI sent in stderr: "PHP message: SHOUT : RADIO             : Adding New Radio StationPHP message: MARK  : RADIO             :   Name  : 1LIVE diggi (Top 40 & Pop Music)PHP message: MARK  : RADIO             :   Image : getRemoteImage.php?url=http://cdn-profiles.tunein.com/s45087/images/logoq.pngPHP message: MARK  : RADIO             :   URL   : http://opml.radiotime.com/Tune.ashx?id=s45087PHP message: MARK  : RADIO             :   Adding New Track http://opml.radiotime.com/Tune.ashx?id=s45087 to station 1PHP message: PHP Notice:  fputs(): send of 52 bytes failed with errno=32 Broken pipe in /app/rompr/player/mpd/mpdinterface.php on line 238PHP message: PHP Notice:  fputs(): send of 9 bytes failed with errno=32 Broken pipe in /app/rompr/player/mpd/mpdinterface.php on line 238PHP message: WARN  : MPD               : Socket Write Error for command_list_end - RetryingPHP message: WARN  : MPD               : Socket Write Error for command_list_end - RetryingPHP message: WARN  : MPD               : Socket Write Error for command_list_end - RetryingPHP message: WARN  : MPD               : Socket Write Error for command_list_end - RetryingPHP message: WARN  : MPD               : Socket Write Error for command_list_end - RetryingPHP message: ERROR : MPD               : Failure to fput command command_list_endPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: ERROR : MPD               : Failure to fput command statusPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Socket Write Error for status - RetryingPHP message: WARN  : MPD               : Sock
#5

Firstly, given that it tried to use mysql the first time, it might still be set to that. Go to the setup screen (rompr?setup) and check.

But the communication issue is more concerning. Something is blocking, or closing, socket connections. That's some low level networking problem, not a Rompr issue. Maybe related to docker, I don't know anything about docker but that's the only thing I can think it might be. Or some firewall thing? SELinux? I don't know, but Rompr log traces are not going to be a help in figuring out what it is.

#6

You could try installing mpd instead of mopidy and see if that works. You'll have to remove the contents of rompr/prefs, and disable Mopidy. If it is a networking problem then Python would be the obvious culprit to look at first.

nGaDOLEr · · author
#7

Thank you for the advice. I will try to work my way through the other components and check for problems.
I'll post an update when I succeeded (or gave up).

nGaDOLEr · · author
#8

So after your advice I took a step back and tried to map the data flow.
And, almost as expected, there is a super easy fix for my problem, which I figured out searching für the right terms.

The following is my understanding of things and might be a bit colloquial, so please correct me if I am telling nonsense:
Docker is deploying seperate containers for each service (mopidy and rompr), which are by default connected to the host via a bridge network (this site has some good visualisations).
Selecting my localhost or server ip-address for the mopidy connection was my first fault. I could probably have set all IP's to 0.0.0.0, but thats probably not the most adequate (and secure) thing to do.
Much easier is to deploy the mopidy docker container on the localhost, which is very easy to be done using a

network_mode: "host"

command in the composed file (as to be found in the docker-docs).
The RompR-Container needs to still be deployed on bridge network (at least with OMV running), becaus OMV already listens to Port 80 and the rompr-GUI is not accessible otherwise.

It might also be possible just to install the sqlite3 (or mariaDB) in the mopidy-container, but then again the container-updating would be more of an issue.

So probably for most users common sense, for me your input helped a lot and I am looking forward to explore RompR on my NAS.

Cheers!

nGaDOLEr accepted post #5 as the answer
#9
#10

Thanks for posting back. I mean, wow, I don't know anything at all about Docker, it seems to be a way to make simple things really really complicated? ;)

Hopefully this post will help other people, 'cos I'm not going within a mile of it.