FT 2023.2 issue. FTP and miniDLNA both services stop after any changes in "Basic" settings

[Sysres]

@Pedro, I am sorry but you jumped to a conclusion in ticket #282 way too quickly! As an experienced FT user I deserve the benefit of a doubt. Do you really think I don’t know that FTP and miniDLNA services must be running before they can be used? I have been working with computers as system admin since IBM System/360 and PDP-11 days. With the same success you could have told me that I forgot to turn the power “on” or forgot to plug in the USB drive, etc. If an issue doesn’t exist on you router model it doesn’t mean it doesn’t exist on all others. I believe you and @M_ars use Netgear models primarily. I only use ASUS models. Anyway, I believe this issue showed up initially in FT 2023.1 and wasn’t present in 2022.3 and earlier. Both FTP and miniDLNA are running, see 1st screenshot below. Then I go to Basic settings and simply click on “Save”, see 2nd screenshot. Then both FTP and miniDLNA don’t restart, see 3rd screenshot. Also attached is portion of the Syslog reflecting events after I pressed the “Save” button in Basic settings. As you can clearly see minidlna is stopped, vsftpd is stopped as well many other services but when this process is over FTP and miniDLNA do NOT restart. Also there are some strange errors in syslog: “preinit[1]: ERROR in: _ifconfig line: 70 - file "": No such device”. If you would like to play with my test RT-AC1900P router I can give you remote access to it, so you can see it yourself. All my ASUS routers with FT 2023.2 behave like this. Let me know if you are interested and how to give you login information. Thanks.

‌

‌

Mar 28 13:15:14 DG-ASUS daemon.warn dnsmasq[20416]: no servers found in /etc/resolv.dnsmasq, will retry
Mar 28 13:15:14 DG-ASUS daemon.warn minidlna[21244]: received signal 15, good-bye
Mar 28 13:15:15 DG-ASUS user.info preinit[1]: minidlna is stopped
Mar 28 13:15:15 DG-ASUS user.info preinit[1]: vsftpd is stopped
Mar 28 13:15:15 DG-ASUS user.debug wsdd2[20427]: 'Terminated' signal received.
Mar 28 13:15:15 DG-ASUS user.info wsdd2[20427]: terminating.
Mar 28 13:15:15 DG-ASUS user.info preinit[1]: samba daemon is stopped
Mar 28 13:15:15 DG-ASUS user.info preinit[1]: pptpd is stopped
Mar 28 13:15:15 DG-ASUS user.info preinit[1]: rstats stopped
Mar 28 13:15:15 DG-ASUS user.info preinit[1]: cstats stopped
Mar 28 13:15:15 DG-ASUS user.info preinit[1]: httpd is stopped
Mar 28 13:15:15 DG-ASUS daemon.info dnsmasq[20416]: exiting on receipt of SIGTERM
Mar 28 13:15:15 DG-ASUS user.info preinit[1]: wireless band steering is stopped
Mar 28 13:15:15 DG-ASUS user.info preinit[1]: wireless roaming assistant is stopped
Mar 28 13:15:15 DG-ASUS user.info preinit[1]: irqbalance is stopped
Mar 28 13:15:15 DG-ASUS daemon.notice miniupnpd[20532]: shutting down MiniUPnPd
Mar 28 13:15:15 DG-ASUS user.notice adblock[22049]: Stopping Adblock/DNS-filtering...
Mar 28 13:15:21 DG-ASUS kern.info kernel: br0: port 3(eth2) entering forwarding state
Mar 28 13:15:21 DG-ASUS kern.info kernel: br0: port 2(eth1) entering forwarding state
Mar 28 13:15:21 DG-ASUS kern.info kernel: br0: port 1(vlan1) entering forwarding state
Mar 28 13:15:21 DG-ASUS kern.info kernel: device eth0 left promiscuous mode
Mar 28 13:15:21 DG-ASUS kern.info kernel: device vlan1 left promiscuous mode
Mar 28 13:15:21 DG-ASUS kern.info kernel: br0: port 1(vlan1) entering disabled state
Mar 28 13:15:21 DG-ASUS kern.info kernel: device eth1 left promiscuous mode
Mar 28 13:15:21 DG-ASUS kern.info kernel: br0: port 2(eth1) entering disabled state
Mar 28 13:15:21 DG-ASUS kern.info kernel: device eth2 left promiscuous mode
Mar 28 13:15:21 DG-ASUS kern.info kernel: br0: port 3(eth2) entering disabled state
Mar 28 13:15:21 DG-ASUS user.err preinit[1]: ERROR in: _ifconfig line: 70 - file "": No such device
Mar 28 13:15:21 DG-ASUS user.err preinit[1]: ERROR in: _ifconfig line: 70 - file "": No such device
Mar 28 13:15:21 DG-ASUS user.err preinit[1]: ERROR in: _ifconfig line: 70 - file "": No such device
Mar 28 13:15:21 DG-ASUS user.info preinit[1]: FreshTomato RESTART ...
Mar 28 13:15:21 DG-ASUS kern.notice kernel: klogd: exiting
Mar 28 13:15:21 DG-ASUS syslog.info syslogd exiting
Mar 28 13:15:21 DG-ASUS syslog.info syslogd started: BusyBox v1.36.0
Mar 28 13:15:21 DG-ASUS kern.notice kernel: klogd started: BusyBox v1.36.0 (2023-03-16 08:47:17 CET)
Mar 28 13:15:23 DG-ASUS user.info sd-idle[22176]: initialized
Mar 28 13:15:23 DG-ASUS kern.info kernel: device vlan1 entered promiscuous mode
Mar 28 13:15:23 DG-ASUS kern.info kernel: device eth0 entered promiscuous mode
Mar 28 13:15:23 DG-ASUS kern.info kernel: device eth1 entered promiscuous mode
Mar 28 13:15:23 DG-ASUS kern.info kernel: device eth2 entered promiscuous mode
Mar 28 13:15:23 DG-ASUS kern.info kernel: br0: port 3(eth2) entering forwarding state
Mar 28 13:15:23 DG-ASUS kern.info kernel: br0: port 3(eth2) entering forwarding state
Mar 28 13:15:23 DG-ASUS kern.info kernel: br0: port 2(eth1) entering forwarding state
Mar 28 13:15:23 DG-ASUS kern.info kernel: br0: port 2(eth1) entering forwarding state
Mar 28 13:15:23 DG-ASUS daemon.info dnsmasq[22231]: started, version 2.89 cachesize 4096
Mar 28 13:15:23 DG-ASUS daemon.info dnsmasq[22231]: compile time options: IPv6 GNU-getopt no-RTC no-DBus no-UBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP no-conntrack ipset no-nftset Tomato-helper auth cryptohash DNSSEC no-ID loop-detect inotify dumpfile
Mar 28 13:15:23 DG-ASUS daemon.info dnsmasq[22231]: asynchronous logging enabled, queue limit is 5 messages
Mar 28 13:15:23 DG-ASUS daemon.info dnsmasq-dhcp[22231]: DHCP, IP range 192.168.1.100 -- 192.168.1.254, lease time 1d
Mar 28 13:15:23 DG-ASUS daemon.warn dnsmasq[22231]: no servers found in /etc/resolv.dnsmasq, will retry
Mar 28 13:15:23 DG-ASUS daemon.info dnsmasq[22231]: read /etc/hosts - 3 names
Mar 28 13:15:23 DG-ASUS daemon.info dnsmasq[22231]: read /etc/hosts.dnsmasq - 5 names
Mar 28 13:15:23 DG-ASUS kern.info kernel: br0: port 1(vlan1) entering forwarding state
Mar 28 13:15:23 DG-ASUS kern.info kernel: br0: port 1(vlan1) entering forwarding state
Mar 28 13:15:24 DG-ASUS user.info preinit[1]: starting rstats
Mar 28 13:15:24 DG-ASUS user.info preinit[1]: starting cstats
Mar 28 13:15:24 DG-ASUS user.info preinit[1]: wireless band steering is stopped
Mar 28 13:15:24 DG-ASUS user.info preinit[1]: wireless band steering disabled
Mar 28 13:15:24 DG-ASUS user.info preinit[1]: wireless roaming assistant is stopped
Mar 28 13:15:24 DG-ASUS user.info preinit[1]: irqbalance is started
Mar 28 13:15:24 DG-ASUS user.info preinit[1]: MultiWAN: MWAN is 1 (max 4)
Mar 28 13:15:25 DG-ASUS user.info preinit[1]: Asus RT-AC1900P: FreshTomato 2023.2 K26ARM USB VPN-64K
Mar 28 13:15:25 DG-ASUS daemon.info dnsmasq[22231]: exiting on receipt of SIGTERM
Mar 28 13:15:25 DG-ASUS daemon.info dnsmasq[22312]: started, version 2.89 cachesize 4096
Mar 28 13:15:25 DG-ASUS daemon.info dnsmasq[22312]: compile time options: IPv6 GNU-getopt no-RTC no-DBus no-UBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP no-conntrack ipset no-nftset Tomato-helper auth cryptohash DNSSEC no-ID loop-detect inotify dumpfile
Mar 28 13:15:25 DG-ASUS daemon.info dnsmasq[22312]: asynchronous logging enabled, queue limit is 5 messages
Mar 28 13:15:25 DG-ASUS daemon.info dnsmasq-dhcp[22312]: DHCP, IP range 192.168.1.100 -- 192.168.1.254, lease time 1d
Mar 28 13:15:25 DG-ASUS daemon.info dnsmasq[22312]: reading /etc/resolv.dnsmasq
Mar 28 13:15:25 DG-ASUS daemon.info dnsmasq[22312]: using nameserver 208.67.222.123#53
Mar 28 13:15:25 DG-ASUS daemon.info dnsmasq[22312]: using nameserver 208.67.220.123#53
Mar 28 13:15:25 DG-ASUS daemon.info dnsmasq[22312]: read /etc/hosts - 3 names
Mar 28 13:15:25 DG-ASUS daemon.info dnsmasq[22312]: read /etc/hosts.dnsmasq - 5 names
Mar 28 13:15:25 DG-ASUS daemon.info dnsmasq[22312]: exiting on receipt of SIGTERM
Mar 28 13:15:25 DG-ASUS user.notice adblock[22049]: Exiting 1 - Execution time: 00h 00m 10s
Mar 28 13:15:26 DG-ASUS daemon.info dnsmasq[22324]: started, version 2.89 cachesize 4096
Mar 28 13:15:26 DG-ASUS daemon.info dnsmasq[22324]: compile time options: IPv6 GNU-getopt no-RTC no-DBus no-UBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP no-conntrack ipset no-nftset Tomato-helper auth cryptohash DNSSEC no-ID loop-detect inotify dumpfile
Mar 28 13:15:26 DG-ASUS daemon.info dnsmasq[22324]: asynchronous logging enabled, queue limit is 5 messages
Mar 28 13:15:26 DG-ASUS daemon.info dnsmasq-dhcp[22324]: DHCP, IP range 192.168.1.100 -- 192.168.1.254, lease time 1d
Mar 28 13:15:26 DG-ASUS daemon.info dnsmasq[22324]: reading /etc/resolv.dnsmasq
Mar 28 13:15:26 DG-ASUS daemon.info dnsmasq[22324]: using nameserver 208.67.222.123#53
Mar 28 13:15:26 DG-ASUS daemon.info dnsmasq[22324]: using nameserver 208.67.220.123#53
Mar 28 13:15:26 DG-ASUS daemon.info dnsmasq[22324]: read /etc/hosts - 3 names
Mar 28 13:15:26 DG-ASUS daemon.info dnsmasq[22324]: read /etc/hosts.dnsmasq - 5 names
Mar 28 13:15:26 DG-ASUS user.info preinit[1]: httpd is stopped
Mar 28 13:15:28 DG-ASUS user.info preinit[1]: httpd is started
Mar 28 13:15:28 DG-ASUS daemon.info dnsmasq[22324]: exiting on receipt of SIGTERM
Mar 28 13:15:28 DG-ASUS daemon.info dnsmasq[22497]: started, version 2.89 cachesize 4096
Mar 28 13:15:28 DG-ASUS daemon.info dnsmasq[22497]: compile time options: IPv6 GNU-getopt no-RTC no-DBus no-UBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP no-conntrack ipset no-nftset Tomato-helper auth cryptohash DNSSEC no-ID loop-detect inotify dumpfile
Mar 28 13:15:28 DG-ASUS daemon.info dnsmasq[22497]: asynchronous logging enabled, queue limit is 5 messages
Mar 28 13:15:28 DG-ASUS daemon.info dnsmasq-dhcp[22497]: DHCP, IP range 192.168.1.100 -- 192.168.1.254, lease time 1d
Mar 28 13:15:28 DG-ASUS daemon.info dnsmasq[22497]: reading /etc/resolv.dnsmasq
Mar 28 13:15:28 DG-ASUS daemon.info dnsmasq[22497]: using nameserver 208.67.222.123#53
Mar 28 13:15:28 DG-ASUS daemon.info dnsmasq[22497]: using nameserver 208.67.220.123#53
Mar 28 13:15:28 DG-ASUS daemon.info dnsmasq[22497]: read /etc/hosts - 3 names
Mar 28 13:15:28 DG-ASUS daemon.info dnsmasq[22497]: read /etc/hosts.dnsmasq - 5 names
Mar 28 13:15:28 DG-ASUS user.info preinit[1]: samba daemon is started
Mar 28 13:15:28 DG-ASUS user.info wsdd2[22508]: starting.
Mar 28 13:15:30 DG-ASUS daemon.notice miniupnpd[22622]: version 2.3.3 started
Mar 28 13:15:30 DG-ASUS daemon.notice miniupnpd[22622]: HTTP listening on port 7162
Mar 28 13:15:30 DG-ASUS daemon.warn miniupnpd[22622]: no HTTP IPv6 address, disabling IPv6
Mar 28 13:15:30 DG-ASUS daemon.notice miniupnpd[22622]: Listening for NAT-PMP/PCP traffic on port 5351

‌

[pedro]

OK, so now I at least undestand what you mean by “clicking on basic page”.

So only minidlna and ftpd are affected?

[Sysres]

@Pedro, I am sorry but you are making things up. I never said “clicking on basic page”. In ticket #282 I clearly specified “…stops after any changes in "Basic" settings” and later in the ticket itself “When you make any changes to “Basic” settings”. I also referred earlier ticket #275 which had very similar issue with HTTPD service not restarting automatically. This abundance of information should have given you a clue where to look for. Yet you dismissed everything as user error. I used to code myself in the 80th on Assembler and COBOL on good old IBM Mainframe and know how difficult it is sometimes to find an error. Almost every Mainframe model was different from each other and exact same code could produce different results due to slight equipment differences.

Anyway, to answer your question. Yes, it looks like ftpd and minidlna are clearly affected. But in syslog there are 3 separate “preinit[1]: ERROR in: _ifconfig line: 70 - file "": No such device”. Such errors don’t exist in syslog after the cold boot of the router and everything works fine. My guess there is one more services which doesn’t start properly. I have noticed that statistics is no longer being collected sometimes ever so cstats and rstats both being restarted after changes to “Basic” settings. This could be unintended consequences. It doesn’t happen all the time. In such cases the only way to resume statistics is to go back to “Bandwidth Monitoring” and simply click on “Save”. This triggers a restart of rstats and it works fine after that. There is also an open ticket about statistics being stopped after certain period of time. This issue is intermittent and I experience it once in a while. Hope this helps. Thanks.

[pedro]

rc: init.c: fix restart of some services when using SIGHUP on init (resolves #284)

→ <<cset feb7f03789a7>>