TorRouter v22.03.5

Post Reply
admin
Site Admin
Posts: 473
Joined: 06 Feb 2007, 13:36

TorRouter v22.03.5

Post by admin »

Image

First build has Tor 4.7.14 active.
There will be a version with Tor 4.8.5 available soon.

Last update on my test-machine went wrong due to 'Out Of Memory' errors.
Hang during upload, no response, even on TTL-serial (very, very slow).
Reboot the router is mainly the only option.

Work-around is to stop Tor before updating, and make sure it is really stopped.
Then the update works fine, although Tor is started too early (again) and this should be fixed too.
Do the Time check after an update and reboot the router, should fix timing issues with Tor.

Startup sequence:

Code: Select all

Start priority	Initscript	
00	urngd	
00	sysfixtime	
10	boot	
10	system	
11	fstab	
11	sysctl	
12	log	
12	rpcd	
13	openssl	
19	dropbear	
19	wpad	
19	firewall	
19	dnsmasq	
20	network	
49	htpdate	
50	uhttpd	
50	tor	
50	cron	
50	br2684ctl	
79	luci_statistics	
80	ucitrack	
80	collectd	
80	blockd	
90	openvpn	
94	gpio_switch	
95	done	
95	privoxy	
96	led	
97	dsl_control	
98	sysntpd	
99	p910nd	
99	urandom_seed



new:


00	urngd	
00	sysfixtime	
10	boot	
10	system	
11	fstab	
11	sysctl	
12	log	
12	rpcd	
13	openssl	
19	dropbear	
19	wpad	
19	firewall	
19	dnsmasq	
20	network	
49	htpdate	
50	uhttpd	
50	cron	
50	br2684ctl	
50	privoxy			*
79	luci_statistics	
80	ucitrack	
80	collectd	
80	blockd	
90	openvpn	
94	gpio_switch	
95	done	
96	led	
97	dsl_control	
98	sysntpd	
98	tor			*
99	p910nd	
99	urandom_seed
DG.
admin
Site Admin
Posts: 473
Joined: 06 Feb 2007, 13:36

Re: TorRouter v22.03.5, errors

Post by admin »

System log:

Code: Select all

Wed Sep 27 16:55:21 2023 daemon.notice hostapd: nl80211: nl80211_recv_beacons->nl_recvmsgs failed: -5
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.231757] hostapd invoked oom-killer: gfp_mask=0x100cca(GFP_HIGHUSER_MOVABLE), order=0, oom_score_adj=0
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.240167] CPU: 0 PID: 1503 Comm: hostapd Not tainted 5.10.176 #0
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.246418] Stack : 8072e59c 7fffffff 0000000e 8007e0c0 80840000 8072f2d4 00000000 00000000
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.254957]         827f5b7c 80980000 806ff000 80fbe34c 807cab47 00000001 827f5b20 27d85571
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.263487]         00000000 00000000 806ff000 827f59b8 ffffefff 00000000 00000000 ffffffea
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.272017]         00000131 827f59c4 00000131 807cf870 80000000 00000001 00000000 80700000
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.280556]         00000001 00200000 ffffffff 7fffffff 00000001 00000030 00000000 80980000
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.289075]         ...
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.291694] Call Trace:
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.294306] [<8000bf20>] show_stack+0x30/0x100
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.298939] [<80318db8>] dump_stack+0x9c/0xcc
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.303452] [<8010c408>] dump_header+0x6c/0x270
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.308156] [<8010cab8>] oom_kill_process+0x4ac/0x4ec
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.313368] [<8010d1d0>] out_of_memory+0x214/0x590
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.318348] [<8014f438>] __alloc_pages_nodemask+0x950/0xcec
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.324074] [<80108e3c>] pagecache_get_page+0x148/0x440
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.329481] [<8010ad00>] filemap_fault+0x7a0/0x968
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.334436] [<80132f14>] __do_fault+0x40/0x118
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.339060] [<80138784>] handle_mm_fault+0x784/0xb50
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.344193] [<8001b100>] do_page_fault+0x2b0/0x564
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.349158] [<80020ee8>] tlb_do_page_fault_0+0x118/0x120
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.354614]
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.357408] Mem-Info:
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.358765] active_anon:4504 inactive_anon:15001 isolated_anon:0
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.358765]  active_file:214 inactive_file:210 isolated_file:7
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.358765]  unevictable:0 dirty:0 writeback:0
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.358765]  slab_reclaimable:382 slab_unreclaimable:2588
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.358765]  mapped:5465 shmem:7665 pagetables:102 bounce:0
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.358765]  free:4534 free_pcp:52 free_cma:0
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.391462] Node 0 active_anon:18016kB inactive_anon:60004kB active_file:856kB inactive_file:812kB unevictable:0kB isolated(anon):0kB isolated(file):28kB mapped:21860kB dirty:0kB writeback:0kB shmem:30660kB writeback_tmp:0kB kernel_stack:760kB all_unreclaimable? no
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.415463] Normal free:18136kB min:16384kB low:20480kB high:24576kB reserved_highatomic:4096KB active_anon:18016kB inactive_anon:60004kB active_file:868kB inactive_file:928kB unevictable:0kB writepending:0kB present:131072kB managed:121068kB mlocked:0kB pagetables:408kB bounce:0kB free_pcp:224kB local_pcp:140kB free_cma:0kB
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.444064] lowmem_reserve[]: 0 0
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.447883] Normal: 379*4kB (UMEH) 131*8kB (UMEH) 37*16kB (UMEH) 21*32kB (UMEH) 5*64kB (UME) 3*128kB (UME) 2*256kB (M) 2*512kB (UM) 2*1024kB (ME) 1*2048kB (U) 2*4096kB (M) = 18356kB
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.464274] 8076 total pagecache pages
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.467842] 0 pages in swap cache
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.471273] Swap cache stats: add 0, delete 0, find 0/0
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.476652] Free swap  = 0kB
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.479769] Total swap = 0kB
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.482762] 32768 pages RAM
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.485704] 0 pages HighMem/MovableOnly
Wed Sep 27 16:55:41 2023 kern.warn kernel: [1769335.489764] 2501 pages reserved
Wed Sep 27 16:55:41 2023 kern.info kernel: [1769335.493021] Tasks state (memory values in pages):
Wed Sep 27 16:55:41 2023 kern.info kernel: [1769335.497939] [  pid  ]   uid  tgid total_vm      rss pgtables_bytes swapents oom_score_adj name
Wed Sep 27 16:55:41 2023 kern.info kernel: [1769335.506969] [    723]    81   723      358       35    20480        0             0 ubusd
Wed Sep 27 16:55:41 2023 kern.info kernel: [1769335.515236] [    759]     0   759      277       14    20480        0             0 urngd
Wed Sep 27 16:55:41 2023 kern.info kernel: [1769335.523772] [   1152]   514  1152      335       32    16384        0             0 logd
Wed Sep 27 16:55:41 2023 kern.info kernel: [1769335.531737] [   1206]     0  1206      574       86    20480        0             0 rpcd
Wed Sep 27 16:55:41 2023 kern.info kernel: [1769335.540167] [   1397]     0  1397      302       13    20480        0             0 dropbear
Wed Sep 27 16:55:41 2023 kern.info kernel: [1769335.548620] [   1503]     0  1503     1138      266    24576        0             0 hostapd
Wed Sep 27 16:55:41 2023 kern.info kernel: [1769335.557495] [   1566]     0  1566      455       88    16384        0             0 netifd
Wed Sep 27 16:55:41 2023 kern.info kernel: [1769335.565537] [   1789]     0  1789     1149      145    24576        0             0 uhttpd
Wed Sep 27 16:55:41 2023 kern.info kernel: [1769335.574058] [   1893]     0  1893      388       23    16384        0             0 blockd
Wed Sep 27 16:55:41 2023 kern.info kernel: [1769335.582486] [   1951]     0  1951     1074      150    24576        0             0 collectd
Wed Sep 27 16:55:41 2023 kern.info kernel: [1769335.591103] [   2533]   453  2533      363       22    20480        0             0 dnsmasq
Wed Sep 27 16:55:41 2023 kern.info kernel: [1769335.599644] [   4647]    52  4647    18084    15982    98304        0             0 tor
Wed Sep 27 16:55:41 2023 kern.info kernel: [1769335.608001] [   5273]     0  5273      327       11    16384        0             0 udhcpc
Wed Sep 27 16:55:41 2023 kern.info kernel: [1769335.616188] [   5382]     0  5382      327       12    20480        0             0 ntpd
Wed Sep 27 16:55:41 2023 kern.info kernel: [1769335.624663] [   5579]  8118  5579     1331      486    20480        0             0 privoxy
Wed Sep 27 16:55:41 2023 kern.info kernel: [1769335.633062] [   5599]     0  5599      249        9    20480        0             0 askfirst
Wed Sep 27 16:55:41 2023 kern.info kernel: [1769335.641670] oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),task=tor,pid=4647,uid=52
Wed Sep 27 16:55:41 2023 kern.err kernel: [1769335.649972] Out of memory: Killed process 4647 (tor) total-vm:72336kB, anon-rss:43492kB, file-rss:148kB, shmem-rss:20288kB, UID:52 pgtables:96kB oom_score_adj:0
Wed Sep 27 17:28:46 2023 daemon.notice hostapd: wlan0: AP-STA-DISCONNECTED fa:51:0a:10:8e:84
Wed Sep 27 17:28:46 2023 daemon.info hostapd: wlan0: STA fa:51:0a:10:8e:84 IEEE 802.11: disassociated
Wed Sep 27 17:28:47 2023 daemon.info hostapd: wlan0: STA fa:51:0a:10:8e:84 IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Kernel log:

Code: Select all

[1769335.231757] hostapd invoked oom-killer: gfp_mask=0x100cca(GFP_HIGHUSER_MOVABLE), order=0, oom_score_adj=0
[1769335.240167] CPU: 0 PID: 1503 Comm: hostapd Not tainted 5.10.176 #0
[1769335.246418] Stack : 8072e59c 7fffffff 0000000e 8007e0c0 80840000 8072f2d4 00000000 00000000
[1769335.254957]         827f5b7c 80980000 806ff000 80fbe34c 807cab47 00000001 827f5b20 27d85571
[1769335.263487]         00000000 00000000 806ff000 827f59b8 ffffefff 00000000 00000000 ffffffea
[1769335.272017]         00000131 827f59c4 00000131 807cf870 80000000 00000001 00000000 80700000
[1769335.280556]         00000001 00200000 ffffffff 7fffffff 00000001 00000030 00000000 80980000
[1769335.289075]         ...
[1769335.291694] Call Trace:
[1769335.294306] [<8000bf20>] show_stack+0x30/0x100
[1769335.298939] [<80318db8>] dump_stack+0x9c/0xcc
[1769335.303452] [<8010c408>] dump_header+0x6c/0x270
[1769335.308156] [<8010cab8>] oom_kill_process+0x4ac/0x4ec
[1769335.313368] [<8010d1d0>] out_of_memory+0x214/0x590
[1769335.318348] [<8014f438>] __alloc_pages_nodemask+0x950/0xcec
[1769335.324074] [<80108e3c>] pagecache_get_page+0x148/0x440
[1769335.329481] [<8010ad00>] filemap_fault+0x7a0/0x968
[1769335.334436] [<80132f14>] __do_fault+0x40/0x118
[1769335.339060] [<80138784>] handle_mm_fault+0x784/0xb50
[1769335.344193] [<8001b100>] do_page_fault+0x2b0/0x564
[1769335.349158] [<80020ee8>] tlb_do_page_fault_0+0x118/0x120
[1769335.354614] 
[1769335.357408] Mem-Info:
[1769335.358765] active_anon:4504 inactive_anon:15001 isolated_anon:0
[1769335.358765]  active_file:214 inactive_file:210 isolated_file:7
[1769335.358765]  unevictable:0 dirty:0 writeback:0
[1769335.358765]  slab_reclaimable:382 slab_unreclaimable:2588
[1769335.358765]  mapped:5465 shmem:7665 pagetables:102 bounce:0
[1769335.358765]  free:4534 free_pcp:52 free_cma:0
[1769335.391462] Node 0 active_anon:18016kB inactive_anon:60004kB active_file:856kB inactive_file:812kB unevictable:0kB isolated(anon):0kB isolated(file):28kB mapped:21860kB dirty:0kB writeback:0kB shmem:30660kB writeback_tmp:0kB kernel_stack:760kB all_unreclaimable? no
[1769335.415463] Normal free:18136kB min:16384kB low:20480kB high:24576kB reserved_highatomic:4096KB active_anon:18016kB inactive_anon:60004kB active_file:868kB inactive_file:928kB unevictable:0kB writepending:0kB present:131072kB managed:121068kB mlocked:0kB pagetables:408kB bounce:0kB free_pcp:224kB local_pcp:140kB free_cma:0kB
[1769335.444064] lowmem_reserve[]: 0 0
[1769335.447883] Normal: 379*4kB (UMEH) 131*8kB (UMEH) 37*16kB (UMEH) 21*32kB (UMEH) 5*64kB (UME) 3*128kB (UME) 2*256kB (M) 2*512kB (UM) 2*1024kB (ME) 1*2048kB (U) 2*4096kB (M) = 18356kB
[1769335.464274] 8076 total pagecache pages
[1769335.467842] 0 pages in swap cache
[1769335.471273] Swap cache stats: add 0, delete 0, find 0/0
[1769335.476652] Free swap  = 0kB
[1769335.479769] Total swap = 0kB
[1769335.482762] 32768 pages RAM
[1769335.485704] 0 pages HighMem/MovableOnly
[1769335.489764] 2501 pages reserved
[1769335.493021] Tasks state (memory values in pages):
[1769335.497939] [  pid  ]   uid  tgid total_vm      rss pgtables_bytes swapents oom_score_adj name
[1769335.506969] [    723]    81   723      358       35    20480        0             0 ubusd
[1769335.515236] [    759]     0   759      277       14    20480        0             0 urngd
[1769335.523772] [   1152]   514  1152      335       32    16384        0             0 logd
[1769335.531737] [   1206]     0  1206      574       86    20480        0             0 rpcd
[1769335.540167] [   1397]     0  1397      302       13    20480        0             0 dropbear
[1769335.548620] [   1503]     0  1503     1138      266    24576        0             0 hostapd
[1769335.557495] [   1566]     0  1566      455       88    16384        0             0 netifd
[1769335.565537] [   1789]     0  1789     1149      145    24576        0             0 uhttpd
[1769335.574058] [   1893]     0  1893      388       23    16384        0             0 blockd
[1769335.582486] [   1951]     0  1951     1074      150    24576        0             0 collectd
[1769335.591103] [   2533]   453  2533      363       22    20480        0             0 dnsmasq
[1769335.599644] [   4647]    52  4647    18084    15982    98304        0             0 tor
[1769335.608001] [   5273]     0  5273      327       11    16384        0             0 udhcpc
[1769335.616188] [   5382]     0  5382      327       12    20480        0             0 ntpd
[1769335.624663] [   5579]  8118  5579     1331      486    20480        0             0 privoxy
[1769335.633062] [   5599]     0  5599      249        9    20480        0             0 askfirst
[1769335.641670] oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),task=tor,pid=4647,uid=52
[1769335.649972] Out of memory: Killed process 4647 (tor) total-vm:72336kB, anon-rss:43492kB, file-rss:148kB, shmem-rss:20288kB, UID:52 pgtables:96kB oom_score_adj:0
Looks like an Out of memory error, according the last line :)
And manually Tor was restarted afterwards.

Next builds remove VPN ?


DG.
admin
Site Admin
Posts: 473
Joined: 06 Feb 2007, 13:36

Re: TorRouter v22.03.5

Post by admin »

Definitely Out Of Memory issues...
Watch the available column.

Code: Select all

BusyBox v1.35.0 (2023-04-27 20:28:15 UTC) built-in shell (ash)

  _______                     ________        __
 |       |.-----.-----.-----.|  |  |  |.----.|  |_
 |   -   ||  _  |  -__|     ||  |  |  ||   _||   _|
 |_______||   __|_____|__|__||________||__|  |____|
          |__| W I R E L E S S   F R E E D O M
 -----------------------------------------------------
 TorRouter 22.03.5, r20134-5f15225c1e
 -----------------------------------------------------
root@TorRouter:/# free
              total        used        free      shared  buff/cache   available
Mem:         120540       64740       23948       13264       31852       10492
Swap:             0           0           0
root@TorRouter:/# free
              total        used        free      shared  buff/cache   available
Mem:         120540       69640       21248       17112       29652        5716
Swap:             0           0           0
root@TorRouter:/# free
              total        used        free      shared  buff/cache   available
Mem:         120540       71048       21368       20948       28124         732
Swap:             0           0           0
root@TorRouter:/# free
              total        used        free      shared  buff/cache   available
Mem:         120540       72988       18908       22200       28644           0
Swap:             0           0           0
root@TorRouter:/# free
              total        used        free      shared  buff/cache   available
Mem:         120540       64916       24560       23432       31064        3856
Swap:             0           0           0
root@TorRouter:/# free
              total        used        free      shared  buff/cache   available
Mem:         120540       65000       21544       24788       33996        1856
Swap:             0           0           0
v22.03.x will be last version for the F1?
We even see available = 0 in the overview, it almost crached!

We also know: if the F1 boots fully and Tor is running, in the beginning the F1 has only about 3MB free (web-overview).
And after about 45 minute or so, there will be 20MB more free memory.

Code: Select all

root@TorRouter:/# free
              total        used        free      shared  buff/cache   available
Mem:         120540       66596       18064       24800       35880           0
Swap:             0           0           0
root@TorRouter:/# free
              total        used        free      shared  buff/cache   available
Mem:         120540       45320       41732       24804       33488       21540
Swap:             0           0           0
root@TorRouter:/# uptime
 15:25:08 up 58 min,  load average: 0.20, 0.19, 0.24
Possible due to finishing compressing tor-geoip data into memory? Idk.

And after another reboot, with wifi 'on';

Code: Select all

root@TorRouter:/# uptime
 03:01:16 up 12 min,  load average: 0.04, 0.25, 0.26
root@TorRouter:/# free
              total        used        free      shared  buff/cache   available
Mem:         120540       66684       18032       24928       35824           0
Swap:             0           0           0
root@TorRouter:/# free
              total        used        free      shared  buff/cache   available
Mem:         120540       45452       40992       24928       34096       21008
Swap:             0           0           0
root@TorRouter:/# uptime
 03:22:54 up 33 min,  load average: 0.29, 0.59, 0.38
DG.
Post Reply