Too many open files?

[SkidMark]

Platform: TL-MR3020 (no USB hub)
RAM: 29364 kB total, 3476 kB free (88% used)
SW Version: Meteobridge 4.4 (Oct 6 2020, build 13952), FW 1.4
Uptime: 1 days, 21 hours, 6 minutes Buffer: 1 items (0%)

Don't recall seeing the Too many open files in the log file before, has more info been added to the log data? Or do I have a problem elsewhere?

logger (10.10.2020 18:37:30): open named pipe "/dev/meteobridge" failed: Too many open files
logger (10.10.2020 18:37:37): message above repeats 2 times.
logger (10.10.2020 18:38:29): station 0 (Vantage), error make socket 192.168.1.2: port 22222: Too many open files
logger (10.10.2020 18:38:30): historical data loaded (27 records).

Code: Select all

wnetd  (09.10.2020 23:49:11): process started.
system (09.10.2020 23:49:11): start of logging process initiated.
wnetd  (09.10.2020 23:49:11): send data thread started (pid:2333 tid:2335)
logger (09.10.2020 23:49:12): historical data loaded (27 records).
logger (09.10.2020 23:49:12): data logger (version 5.1d, build 13952) started.
logger (09.10.2020 23:49:13): connect station 0 (Vantage via TCP/IP).
logger (09.10.2020 23:49:14): station 0 (Vantage), wakeup sent.
logger (09.10.2020 23:49:17): sensors are now providing data.
logger (09.10.2020 23:49:17): services started.
logger (09.10.2020 23:49:19): station 0 (Vantage), no need to update console date/time.
logger (09.10.2020 23:49:20): station 0 (Vantage), setup script executed.
logger (09.10.2020 23:49:20): station 0 (Vantage), internal logger activated.
logger (09.10.2020 23:49:20): station 0 (Vantage), syncing.
logger (09.10.2020 23:49:30): station 0 (Vantage), gettime request sent.
logger (09.10.2020 23:49:30): station 0 (Vantage), no Vantage time offset.
logger (09.10.2020 23:49:30): station 0 (Vantage), eeprom request sent.
logger (09.10.2020 23:49:30): station 0 (Vantage), rain collector size 0.01 inch per tick.
logger (09.10.2020 23:49:30): station 0 (Vantage), transmitter 1 active as station type #0 "ISS".
logger (09.10.2020 23:49:30): station 0 (Vantage), firmware version request sent.
logger (09.10.2020 23:49:30): station 0 (Vantage), firmware version 3.80 (supports LOOP2)
logger (09.10.2020 23:49:30): station 0 (Vantage), no reading of Vantage's internal logger.
logger (10.10.2020 18:37:30): open named pipe "/dev/meteobridge" failed: Too many open files
logger (10.10.2020 18:37:37): message above repeats 2 times.
logger (10.10.2020 18:38:29): station 0 (Vantage), error make socket 192.168.1.2: port 22222: Too many open files
logger (10.10.2020 18:38:30): historical data loaded (27 records).
logger (10.10.2020 18:38:30): data logger (version 5.1d, build 13952) started.
logger (10.10.2020 18:38:31): connect station 0 (Vantage via TCP/IP).
logger (10.10.2020 18:38:31): station 0 (Vantage), wakeup sent.
logger (10.10.2020 18:38:34): sensors are now providing data.
logger (10.10.2020 18:38:34): services started.
logger (10.10.2020 18:38:37): station 0 (Vantage), no need to update console date/time.
logger (10.10.2020 18:38:37): station 0 (Vantage), setup script executed.
logger (10.10.2020 18:38:37): station 0 (Vantage), internal logger activated.
logger (10.10.2020 18:38:37): station 0 (Vantage), syncing.
logger (10.10.2020 18:38:48): station 0 (Vantage), gettime request sent.
logger (10.10.2020 18:38:48): station 0 (Vantage), no Vantage time offset.
logger (10.10.2020 18:38:48): station 0 (Vantage), eeprom request sent.
logger (10.10.2020 18:38:48): station 0 (Vantage), rain collector size 0.01 inch per tick.
logger (10.10.2020 18:38:48): station 0 (Vantage), transmitter 1 active as station type #0 "ISS".
logger (10.10.2020 18:38:48): station 0 (Vantage), firmware version request sent.
logger (10.10.2020 18:38:48): station 0 (Vantage), firmware version 3.80 (supports LOOP2)
logger (10.10.2020 18:38:48): station 0 (Vantage), no reading of Vantage's internal logger.
logger (11.10.2020 13:26:12): open named pipe "/dev/meteobridge" failed: Too many open files
logger (11.10.2020 13:26:29): station 0 (Vantage), error make socket 192.168.1.2: port 22222: Too many open files
logger (11.10.2020 13:26:29): historical data loaded (27 records).
logger (11.10.2020 13:26:29): data logger (version 5.1d, build 13952) started.
logger (11.10.2020 13:26:30): connect station 0 (Vantage via TCP/IP).
logger (11.10.2020 13:26:30): station 0 (Vantage), wakeup sent.
logger (11.10.2020 13:26:33): sensors are now providing data.
logger (11.10.2020 13:26:33): services started.
logger (11.10.2020 13:26:36): station 0 (Vantage), no need to update console date/time.
logger (11.10.2020 13:26:36): station 0 (Vantage), setup script executed.
logger (11.10.2020 13:26:36): station 0 (Vantage), internal logger activated.
logger (11.10.2020 13:26:37): station 0 (Vantage), syncing.
logger (11.10.2020 13:26:47): station 0 (Vantage), gettime request sent.
logger (11.10.2020 13:26:47): station 0 (Vantage), no Vantage time offset.
logger (11.10.2020 13:26:47): station 0 (Vantage), eeprom request sent.
logger (11.10.2020 13:26:47): station 0 (Vantage), rain collector size 0.01 inch per tick.
logger (11.10.2020 13:26:47): station 0 (Vantage), transmitter 1 active as station type #0 "ISS".
logger (11.10.2020 13:26:47): station 0 (Vantage), firmware version request sent.
logger (11.10.2020 13:26:47): station 0 (Vantage), firmware version 3.80 (supports LOOP2)
logger (11.10.2020 13:26:47): station 0 (Vantage), no reading of Vantage's internal logger.

[admin]

Please use most recent version.

[SkidMark]

Updated, I'll let you know after it runs for a few days.
Thanks

[SkidMark]

Seen a couple more from the last update. What I did see and may not be related is the time between the entries below is 18 hours 42 minutes and then 18 hours 52 minutes. From my post on 11-OCT-2020 the times were 18 hours 48 minutes and 18 hours 48 minutes.

So I'll see if on 15.10.2020 05:20:57 if it will cause the error again.

Platform: TL-MR3020 (no USB hub)
RAM: 29364 kB total, 2928 kB free (90% used)
SW Version: Meteobridge 4.4 (Oct 12 2020, build 13960), FW 1.4
Uptime: 2 days, 0 hours, 19 minutes Buffer: 1 items (0%)

Code: Select all

wnetd  (12.10.2020 20:55:54): process started.
system (12.10.2020 20:55:54): start of logging process initiated.
wnetd  (12.10.2020 20:55:54): send data thread started (pid:2348 tid:2350)
logger (12.10.2020 20:55:54): historical data loaded (27 records).
logger (12.10.2020 20:55:54): data logger (version 5.1d, build 13960) started.
logger (12.10.2020 20:55:55): connect station 0 (Vantage via TCP/IP).
logger (12.10.2020 20:55:55): station 0 (Vantage), wakeup sent.
logger (12.10.2020 20:55:58): sensors are now providing data.
logger (12.10.2020 20:55:58): services started.
logger (12.10.2020 20:56:01): station 0 (Vantage), no need to update console date/time.
logger (12.10.2020 20:56:01): station 0 (Vantage), setup script executed.
logger (12.10.2020 20:56:01): station 0 (Vantage), internal logger activated.
logger (12.10.2020 20:56:01): station 0 (Vantage), syncing.
logger (12.10.2020 20:56:12): station 0 (Vantage), gettime request sent.
logger (12.10.2020 20:56:12): station 0 (Vantage), no Vantage time offset.
logger (12.10.2020 20:56:12): station 0 (Vantage), eeprom request sent.
logger (12.10.2020 20:56:12): station 0 (Vantage), rain collector size 0.01 inch per tick.
logger (12.10.2020 20:56:12): station 0 (Vantage), transmitter 1 active as station type #0 "ISS".
logger (12.10.2020 20:56:12): station 0 (Vantage), firmware version request sent.
logger (12.10.2020 20:56:12): station 0 (Vantage), firmware version 3.80 (supports LOOP2)
logger (12.10.2020 20:56:12): station 0 (Vantage), no reading of Vantage's internal logger.
logger (13.10.2020 15:38:49): open named pipe "/dev/meteobridge" failed: Too many open files
logger (13.10.2020 15:39:48): station 0 (Vantage), error make socket 192.168.1.2: port 22222: Too many open files
logger (13.10.2020 15:39:49): historical data loaded (27 records).
logger (13.10.2020 15:39:49): data logger (version 5.1d, build 13960) started.
logger (13.10.2020 15:39:50): connect station 0 (Vantage via TCP/IP).
logger (13.10.2020 15:39:50): station 0 (Vantage), wakeup sent.
logger (13.10.2020 15:39:53): sensors are now providing data.
logger (13.10.2020 15:39:53): services started.
logger (13.10.2020 15:39:56): station 0 (Vantage), no need to update console date/time.
logger (13.10.2020 15:39:56): station 0 (Vantage), setup script executed.
logger (13.10.2020 15:39:56): station 0 (Vantage), internal logger activated.
logger (13.10.2020 15:39:56): station 0 (Vantage), syncing.
logger (13.10.2020 15:40:07): station 0 (Vantage), gettime request sent.
logger (13.10.2020 15:40:07): station 0 (Vantage), no Vantage time offset.
logger (13.10.2020 15:40:07): station 0 (Vantage), eeprom request sent.
logger (13.10.2020 15:40:07): station 0 (Vantage), rain collector size 0.01 inch per tick.
logger (13.10.2020 15:40:07): station 0 (Vantage), transmitter 1 active as station type #0 "ISS".
logger (13.10.2020 15:40:07): station 0 (Vantage), firmware version request sent.
logger (13.10.2020 15:40:07): station 0 (Vantage), firmware version 3.80 (supports LOOP2)
logger (13.10.2020 15:40:07): station 0 (Vantage), no reading of Vantage's internal logger.
logger (14.10.2020 10:31:45): open named pipe "/dev/meteobridge" failed: Too many open files
logger (14.10.2020 10:32:27): station 0 (Vantage), error make socket 192.168.1.2: port 22222: Too many open files
logger (14.10.2020 10:32:28): historical data loaded (27 records).
logger (14.10.2020 10:32:28): data logger (version 5.1d, build 13960) started.
logger (14.10.2020 10:32:29): connect station 0 (Vantage via TCP/IP).
logger (14.10.2020 10:32:29): station 0 (Vantage), wakeup sent.
logger (14.10.2020 10:32:32): sensors are now providing data.
logger (14.10.2020 10:32:32): services started.
logger (14.10.2020 10:32:35): station 0 (Vantage), no need to update console date/time.
logger (14.10.2020 10:32:35): station 0 (Vantage), setup script executed.
logger (14.10.2020 10:32:35): station 0 (Vantage), internal logger activated.
logger (14.10.2020 10:32:35): station 0 (Vantage), syncing.
logger (14.10.2020 10:32:45): station 0 (Vantage), gettime request sent.
logger (14.10.2020 10:32:45): station 0 (Vantage), no Vantage time offset.
logger (14.10.2020 10:32:45): station 0 (Vantage), eeprom request sent.
logger (14.10.2020 10:32:45): station 0 (Vantage), rain collector size 0.01 inch per tick.
logger (14.10.2020 10:32:45): station 0 (Vantage), transmitter 1 active as station type #0 "ISS".
logger (14.10.2020 10:32:45): station 0 (Vantage), firmware version request sent.
logger (14.10.2020 10:32:45): station 0 (Vantage), firmware version 3.80 (supports LOOP2)
logger (14.10.2020 10:32:45): station 0 (Vantage), no reading of Vantage's internal logger.

[SkidMark]

Well I was off a few minutes.

Code: Select all

logger (14.10.2020 10:32:32): sensors are now providing data.
logger (14.10.2020 10:32:32): services started.
logger (14.10.2020 10:32:35): station 0 (Vantage), no need to update console date/time.
logger (14.10.2020 10:32:35): station 0 (Vantage), setup script executed.
logger (14.10.2020 10:32:35): station 0 (Vantage), internal logger activated.
logger (14.10.2020 10:32:35): station 0 (Vantage), syncing.
logger (14.10.2020 10:32:45): station 0 (Vantage), gettime request sent.
logger (14.10.2020 10:32:45): station 0 (Vantage), no Vantage time offset.
logger (14.10.2020 10:32:45): station 0 (Vantage), eeprom request sent.
logger (14.10.2020 10:32:45): station 0 (Vantage), rain collector size 0.01 inch per tick.
logger (14.10.2020 10:32:45): station 0 (Vantage), transmitter 1 active as station type #0 "ISS".
logger (14.10.2020 10:32:45): station 0 (Vantage), firmware version request sent.
logger (14.10.2020 10:32:45): station 0 (Vantage), firmware version 3.80 (supports LOOP2)
logger (14.10.2020 10:32:45): station 0 (Vantage), no reading of Vantage's internal logger.
logger (15.10.2020 05:31:20): open named pipe "/dev/meteobridge" failed: Too many open files
logger (15.10.2020 05:32:20): message above repeats 3 times.
logger (15.10.2020 05:32:20): station 0 (Vantage), error make socket 192.168.1.2: port 22222: Too many open files
logger (15.10.2020 05:32:20): historical data loaded (27 records).
logger (15.10.2020 05:32:20): data logger (version 5.1d, build 13960) started.
logger (15.10.2020 05:32:21): connect station 0 (Vantage via TCP/IP).
logger (15.10.2020 05:32:22): station 0 (Vantage), wakeup sent.
logger (15.10.2020 05:32:25): sensors are now providing data.
logger (15.10.2020 05:32:25): services started.
logger (15.10.2020 05:32:27): station 0 (Vantage), no need to update console date/time.
logger (15.10.2020 05:32:27): station 0 (Vantage), setup script executed.
logger (15.10.2020 05:32:28): station 0 (Vantage), internal logger activated.
logger (15.10.2020 05:32:28): station 0 (Vantage), syncing.
logger (15.10.2020 05:32:38): station 0 (Vantage), gettime request sent.
logger (15.10.2020 05:32:38): station 0 (Vantage), no Vantage time offset.
logger (15.10.2020 05:32:38): station 0 (Vantage), eeprom request sent.
logger (15.10.2020 05:32:38): station 0 (Vantage), rain collector size 0.01 inch per tick.
logger (15.10.2020 05:32:38): station 0 (Vantage), transmitter 1 active as station type #0 "ISS".
logger (15.10.2020 05:32:38): station 0 (Vantage), firmware version request sent.
logger (15.10.2020 05:32:38): station 0 (Vantage), firmware version 3.80 (supports LOOP2)
logger (15.10.2020 05:32:38): station 0 (Vantage), no reading of Vantage's internal logger.

Also from the System log in expert mode.

Code: Select all

Oct 13 00:59:42 MeteoBridge kern.warn kernel: [  325.830000] done /home/setup.sh
Oct 13 00:59:42 MeteoBridge user.info sysinit: setting up led USB
Oct 13 00:59:42 MeteoBridge user.info sysinit: setting up led WLAN
Oct 13 00:59:42 MeteoBridge user.info sysinit: setting up led LAN
Oct 13 12:54:56 MeteoBridge daemon.notice netifd: lan (687): Sending renew...
Oct 13 12:54:56 MeteoBridge daemon.notice netifd: lan (687): Lease of 192.168.1.3 obtained, lease time 86400
Oct 13 19:39:49 MeteoBridge kern.info kernel: [67532.090000] TCP: too many orphaned sockets
Oct 13 19:39:49 MeteoBridge kern.info kernel: [67532.090000] TCP: too many orphaned sockets
Oct 13 19:39:49 MeteoBridge kern.info kernel: [67532.100000] TCP: too many orphaned sockets
Oct 13 19:39:49 MeteoBridge kern.info kernel: [67532.100000] TCP: too many orphaned sockets
Oct 13 19:39:49 MeteoBridge kern.info kernel: [67532.110000] TCP: too many orphaned sockets
Oct 13 19:39:49 MeteoBridge kern.info kernel: [67532.110000] TCP: too many orphaned sockets
Oct 13 19:39:49 MeteoBridge kern.info kernel: [67532.110000] TCP: too many orphaned sockets
Oct 13 19:39:49 MeteoBridge kern.info kernel: [67532.120000] TCP: too many orphaned sockets
Oct 13 19:39:49 MeteoBridge kern.info kernel: [67532.120000] TCP: too many orphaned sockets
Oct 13 19:39:49 MeteoBridge kern.info kernel: [67532.130000] TCP: too many orphaned sockets
Oct 14 00:54:57 MeteoBridge daemon.notice netifd: lan (687): Sending renew...
Oct 14 00:54:57 MeteoBridge daemon.notice netifd: lan (687): Lease of 192.168.1.3 obtained, lease time 86400
Oct 14 12:54:59 MeteoBridge daemon.notice netifd: lan (687): Sending renew...
Oct 14 12:54:59 MeteoBridge daemon.notice netifd: lan (687): Lease of 192.168.1.3 obtained, lease time 86400
Oct 14 14:32:28 MeteoBridge kern.warn kernel: [135490.700000] net_ratelimit: 666 callbacks suppressed
Oct 14 14:32:28 MeteoBridge kern.info kernel: [135490.710000] TCP: too many orphaned sockets
Oct 14 14:32:28 MeteoBridge kern.info kernel: [135490.850000] TCP: too many orphaned sockets
Oct 14 14:32:28 MeteoBridge kern.info kernel: [135490.850000] TCP: too many orphaned sockets
Oct 14 14:32:28 MeteoBridge kern.info kernel: [135490.860000] TCP: too many orphaned sockets
Oct 14 14:32:28 MeteoBridge kern.info kernel: [135490.860000] TCP: too many orphaned sockets
Oct 14 14:32:28 MeteoBridge kern.info kernel: [135490.860000] TCP: too many orphaned sockets
Oct 14 14:32:28 MeteoBridge kern.info kernel: [135490.870000] TCP: too many orphaned sockets
Oct 14 14:32:28 MeteoBridge kern.info kernel: [135490.870000] TCP: too many orphaned sockets
Oct 14 14:32:28 MeteoBridge kern.info kernel: [135490.880000] TCP: too many orphaned sockets
Oct 14 14:32:28 MeteoBridge kern.info kernel: [135490.880000] TCP: too many orphaned sockets
Oct 15 00:55:00 MeteoBridge daemon.notice netifd: lan (687): Sending renew...
Oct 15 00:55:00 MeteoBridge daemon.notice netifd: lan (687): Lease of 192.168.1.3 obtained, lease time 86400
Oct 15 09:32:20 MeteoBridge kern.warn kernel: [203883.610000] net_ratelimit: 636 callbacks suppressed
Oct 15 09:32:20 MeteoBridge kern.info kernel: [203883.610000] TCP: too many orphaned sockets
Oct 15 09:32:20 MeteoBridge kern.info kernel: [203883.620000] TCP: too many orphaned sockets
Oct 15 09:32:20 MeteoBridge kern.info kernel: [203883.620000] TCP: too many orphaned sockets
Oct 15 09:32:20 MeteoBridge kern.info kernel: [203883.630000] TCP: too many orphaned sockets
Oct 15 09:32:20 MeteoBridge kern.info kernel: [203883.630000] TCP: too many orphaned sockets
Oct 15 09:32:20 MeteoBridge kern.info kernel: [203883.640000] TCP: too many orphaned sockets
Oct 15 09:32:20 MeteoBridge kern.info kernel: [203883.640000] TCP: too many orphaned sockets
Oct 15 09:32:20 MeteoBridge kern.info kernel: [203883.640000] TCP: too many orphaned sockets
Oct 15 09:32:20 MeteoBridge kern.info kernel: [203883.650000] TCP: too many orphaned sockets
Oct 15 09:32:20 MeteoBridge kern.info kernel: [203883.650000] TCP: too many orphaned sockets

[admin]

Never seen these messages on any Meteobridge.

Do you have self-made plugins or scripts running which might not close connections/sockets/file descriptors?

[SkidMark]

Never seen these messages on any Meteobridge.

Do you have self-made plugins or scripts running which might not close connections/sockets/file descriptors?

Not that I am aware of. I am just feeding to Ambient Weather Network, AWEKAS and CWOP. Seen the same message several more times and each is around 18 hours 52 minutes. I have updated to Meteobridge 5.0 (Oct 17 2020, build 13979), FW 1.4. I'll look at it tomorrow and see what it does.