Tracking Center not working in PMG lxc container [SOLVED]

Craig Tosi · Oct 5, 2020

I have a number of PMG installations, some of which are VM's & a couple are LXC containers running within PVE.

I have no problem with the Tracking Center in the VM based installations, but on the 2 LXC based PMG instances the Tracking Center returns no results (ever). It simply returns "No data in database".

I've read that the tracking center uses the system logs to generate it's output but am clueless in terms of how to start troubleshooting this situation. Hoping someone here can point me in the right direction to find a solution.

t.lamprecht · Oct 6, 2020

Hi,

Do you use an ad-blocker? Because most of them have some simple rules which block any requests to URLs including "track" or "tracker", like the Proxmox Mail Gateways Tracking Center uses.
Often this is only done if the PMG is accessed over an URL or maybe even outside the LAN, so it's a bit tricky to detect.

If you use an ad-blocker I'd try to disable it for those PMG instances, it could be something else but we had reports of that making problems in the past, so better to check

Craig Tosi · Oct 6, 2020

Thanks for the response. No, I'm not using an add blocker. I can use the same browser to a VM based PMG instance which works (ie: shows all mail messages routed through PMG for the given search criteria), then point the same browser at an LXC container based PMG instance which shows only "No data in database".

I've confirmed via mail headers for delivered messages definitely being routed through the LXC based PMG instances. Just looking for a starting point to troubleshoot. Does the PMG gui have any kind of process logging?

Stoiko Ivanov · Oct 6, 2020

Craig Tosi said:
I've read that the tracking center uses the system logs to generate it's output but am clueless in terms of how to start troubleshooting this situation. Hoping someone here can point me in the right direction to find a solution.

That's correct the log-tracker (the software behind the Tracking Center) takes the information from '/var/log/syslog' (and the files rotated by logrotate)

* How did you install the PMG container? (did you use the PMG template or install on top of a debian buster container?)
* is 'rsyslog' installed and running in the container?
* does /var/log/syslog contain messages from the mail subsystem (postfix, pmg-smtp-filter,...)

I hope this helps!

Craig Tosi · Oct 8, 2020

Thanks again for your response & apologies for the delay in getting back to you. In answer to your questions:-

1)

* How did you install the PMG container? (did you use the PMG template or install on top of a debian buster container?)

I installed the PMG container from the PMG template available in PVE.

2)

* is 'rsyslog' installed and running in the container?

Yes, rsyslog is installed and shows status as follows:-

Code:

root@pmg6:/var/log# service rsyslog status
● rsyslog.service - System Logging Service
   Loaded: loaded (/lib/systemd/system/rsyslog.service; enabled; vendor preset: enabled)
   Active: active (running) since Tue 2020-10-06 05:17:16 AEDT; 2 days ago
     Docs: man:rsyslogd(8)
           https://www.rsyslog.com/doc/
Main PID: 73 (rsyslogd)
    Tasks: 4 (limit: 4915)
   Memory: 1.4M
   CGroup: /system.slice/rsyslog.service
           └─73 /usr/sbin/rsyslogd -n -iNONE

Oct 06 05:17:16 pmg6 systemd[1]: Starting System Logging Service...
Oct 06 05:17:16 pmg6 rsyslogd[73]: imuxsock: Acquired UNIX socket '/run/systemd/journal/syslog' (fd 3) from systemd.  [v8.1901.0]
Oct 06 05:17:16 pmg6 rsyslogd[73]:  [origin software="rsyslogd" swVersion="8.1901.0" x-pid="73" x-info="https://www.rsyslog.com"] start
Oct 06 05:17:16 pmg6 systemd[1]: Started System Logging Service.

3)

* does /var/log/syslog contain messages from the mail subsystem (postfix, pmg-smtp-filter,...)

Yes, /var/log/syslog contains messages like the following example:-

Code:

Oct  8 17:15:51 pmg6 postfix/postscreen[14596]: CONNECT from [83.68.135.189]:52506 to [10.20.30.13]:25
Oct  8 17:15:53 pmg6 postfix/postscreen[14596]: PASS OLD [83.68.135.189]:52506
Oct  8 17:15:53 pmg6 postfix/smtpd[28655]: connect from suitepmta135189.emsmtp.us[83.68.135.189]
Oct  8 17:15:55 pmg6 pmgpolicy[28255]: SPF says pass
Oct  8 17:15:56 pmg6 postfix/smtpd[28655]: 008C54CB34: client=suitepmta135189.emsmtp.us[83.68.135.189]
Oct  8 17:15:56 pmg6 postfix/cleanup[29643]: 008C54CB34: message-id=<0.1.62.54B.1D69D39058A1BD8.0@suitepmta135189.emsmtp.us>
Oct  8 17:15:57 pmg6 postfix/qmgr[505]: 008C54CB34: from=<suite16@your.fantasticfurniture.com.au>, size=58841, nrcpt=1 (queue active)
Oct  8 17:15:57 pmg6 pmg-smtp-filter[28722]: 2020/10/08-17:15:57 CONNECT TCP Peer: "[127.0.0.1]:56708" Local: "[127.0.0.1]:10024"
Oct  8 17:15:57 pmg6 pmg-smtp-filter[28722]: 4CB355F7EAE9D6DD1F: new mail message-id=<0.1.62.54B.1D69D39058A1BD8.0@suitepmta135189.emsmtp.us>#012
Oct  8 17:15:57 pmg6 postfix/smtpd[28655]: disconnect from suitepmta135189.emsmtp.us[83.68.135.189] ehlo=2 starttls=1 mail=1 rcpt=1 data=1 quit=1 commands=7

I'll be happy to provide anything else needed

Stoiko Ivanov · Oct 8, 2020

hmm - just tested it again on a container - it works here ...

what's the output of:

Code:

 pmg-log-tracker -v -s '2020-05-01 00:00:00'

(anonymize addresses etc. if you want)

Thanks!

Craig Tosi · Oct 8, 2020

Hi Stoiko,

I get:-


root@pmg6:/var/log# pmg-log-tracker -v -s '2020-05-01 00:00:00'
# LogReader: 10703
# Query options
# Start: 2020-05-01 00:00:00 (1588291200)
# End: 2020-10-08 20:07:51 (1602187671)
# End Query Options

Thanks

Stoiko Ivanov · Oct 8, 2020

hmm - would be the same result, if '/var/log/syslog(|.1|.2.gz|.3.gz|...)' were empty or not accessible...

you could let the command run in strace to see if this shows where the problem is rooted:

Code:

strace -yyttTf pmg-log-tracker -v -s '2020-05-01 00:00:00'

Thanks!

Craig Tosi · Oct 8, 2020

Here's the output. It certainly shows that /var/log/syslog.1 does not exist, also that /etc/ld.so.preload does not exist. Much beyond that I'm not sure about.

Code:

root@pmg6:~# strace -yyttTf pmg-log-tracker -v -s '2020-05-01 00:00:00'
06:06:28.663200 execve("/usr/bin/pmg-log-tracker", ["pmg-log-tracker", "-v", "-s", "2020-05-01 00:00:00"], 0x7ffc9c81f5e0 /* 18 vars */) = 0 <0.000265>
06:06:28.663759 brk(NULL)               = 0x55717fd35000 <0.000061>
06:06:28.663925 access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory) <0.000019>
06:06:28.664062 openat(AT_FDCWD, "/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3</etc/ld.so.cache> <0.000058>
06:06:28.664234 fstat(3</etc/ld.so.cache>, {st_mode=S_IFREG|0644, st_size=23306, ...}) = 0 <0.000040>
06:06:28.664434 mmap(NULL, 23306, PROT_READ, MAP_PRIVATE, 3</etc/ld.so.cache>, 0) = 0x7f70141ab000 <0.000055>
06:06:28.664559 close(3</etc/ld.so.cache>) = 0 <0.000035>
06:06:28.664710 openat(AT_FDCWD, "/lib/x86_64-linux-gnu/libz.so.1", O_RDONLY|O_CLOEXEC) = 3</lib/x86_64-linux-gnu/libz.so.1.2.11> <0.000039>
06:06:28.664817 read(3</lib/x86_64-linux-gnu/libz.so.1.2.11>, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\320#\0\0\0\0\0\0"..., 832) = 832 <0.000033>
06:06:28.664934 fstat(3</lib/x86_64-linux-gnu/libz.so.1.2.11>, {st_mode=S_IFREG|0644, st_size=121280, ...}) = 0 <0.000031>
06:06:28.665025 mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f70141a9000 <0.000031>
06:06:28.665109 mmap(NULL, 2216336, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3</lib/x86_64-linux-gnu/libz.so.1.2.11>, 0) = 0x7f7013f8b000 <0.000035>
06:06:28.665202 mprotect(0x7f7013fa7000, 2097152, PROT_NONE) = 0 <0.000037>
06:06:28.665285 mmap(0x7f70141a7000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3</lib/x86_64-linux-gnu/libz.so.1.2.11>, 0x1c000) = 0x7f70141a7000 <0.000036>
06:06:28.665390 close(3</lib/x86_64-linux-gnu/libz.so.1.2.11>) = 0 <0.000030>
06:06:28.665484 openat(AT_FDCWD, "/lib/x86_64-linux-gnu/libdl.so.2", O_RDONLY|O_CLOEXEC) = 3</lib/x86_64-linux-gnu/libdl-2.28.so> <0.000036>
06:06:28.665585 read(3</lib/x86_64-linux-gnu/libdl-2.28.so>, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0000\21\0\0\0\0\0\0"..., 832) = 832 <0.000037>
06:06:28.665694 fstat(3</lib/x86_64-linux-gnu/libdl-2.28.so>, {st_mode=S_IFREG|0644, st_size=14592, ...}) = 0 <0.000010>
06:06:28.665768 mmap(NULL, 16656, PROT_READ, MAP_PRIVATE|MAP_DENYWRITE, 3</lib/x86_64-linux-gnu/libdl-2.28.so>, 0) = 0x7f7013f86000 <0.000019>
06:06:28.665842 mmap(0x7f7013f87000, 4096, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3</lib/x86_64-linux-gnu/libdl-2.28.so>, 0x1000) = 0x7f7013f87000 <0.000040>
06:06:28.665940 mmap(0x7f7013f88000, 4096, PROT_READ, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3</lib/x86_64-linux-gnu/libdl-2.28.so>, 0x2000) = 0x7f7013f88000 <0.000035>
06:06:28.666032 mmap(0x7f7013f89000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3</lib/x86_64-linux-gnu/libdl-2.28.so>, 0x2000) = 0x7f7013f89000 <0.000031>
06:06:28.666129 close(3</lib/x86_64-linux-gnu/libdl-2.28.so>) = 0 <0.000028>
06:06:28.666214 openat(AT_FDCWD, "/lib/x86_64-linux-gnu/librt.so.1", O_RDONLY|O_CLOEXEC) = 3</lib/x86_64-linux-gnu/librt-2.28.so> <0.000034>
06:06:28.666313 read(3</lib/x86_64-linux-gnu/librt-2.28.so>, "\177ELF\2\1\1\3\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\260#\0\0\0\0\0\0"..., 832) = 832 <0.000029>
06:06:28.666418 fstat(3</lib/x86_64-linux-gnu/librt-2.28.so>, {st_mode=S_IFREG|0644, st_size=35808, ...}) = 0 <0.000031>
06:06:28.666510 mmap(NULL, 39904, PROT_READ, MAP_PRIVATE|MAP_DENYWRITE, 3</lib/x86_64-linux-gnu/librt-2.28.so>, 0) = 0x7f7013f7c000 <0.000032>
06:06:28.666595 mmap(0x7f7013f7e000, 16384, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3</lib/x86_64-linux-gnu/librt-2.28.so>, 0x2000) = 0x7f7013f7e000 <0.000042>
06:06:28.666706 mmap(0x7f7013f82000, 8192, PROT_READ, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3</lib/x86_64-linux-gnu/librt-2.28.so>, 0x6000) = 0x7f7013f82000 <0.000035>
06:06:28.666804 mmap(0x7f7013f84000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3</lib/x86_64-linux-gnu/librt-2.28.so>, 0x7000) = 0x7f7013f84000 <0.000036>
06:06:28.666914 close(3</lib/x86_64-linux-gnu/librt-2.28.so>) = 0 <0.000029>
06:06:28.667002 openat(AT_FDCWD, "/lib/x86_64-linux-gnu/libpthread.so.0", O_RDONLY|O_CLOEXEC) = 3</lib/x86_64-linux-gnu/libpthread-2.28.so> <0.000035>
06:06:28.667098 read(3</lib/x86_64-linux-gnu/libpthread-2.28.so>, "\177ELF\2\1\1\3\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0@l\0\0\0\0\0\0"..., 832) = 832 <0.000030>
06:06:28.667185 fstat(3</lib/x86_64-linux-gnu/libpthread-2.28.so>, {st_mode=S_IFREG|0755, st_size=146968, ...}) = 0 <0.000029>
06:06:28.667276 mmap(NULL, 132288, PROT_READ, MAP_PRIVATE|MAP_DENYWRITE, 3</lib/x86_64-linux-gnu/libpthread-2.28.so>, 0) = 0x7f7013f5b000 <0.000033>
06:06:28.667364 mmap(0x7f7013f61000, 61440, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3</lib/x86_64-linux-gnu/libpthread-2.28.so>, 0x6000) = 0x7f7013f61000 <0.000037>
06:06:28.667456 mmap(0x7f7013f70000, 24576, PROT_READ, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3</lib/x86_64-linux-gnu/libpthread-2.28.so>, 0x15000) = 0x7f7013f70000 <0.000033>
06:06:28.667543 mmap(0x7f7013f76000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3</lib/x86_64-linux-gnu/libpthread-2.28.so>, 0x1a000) = 0x7f7013f76000 <0.000033>
06:06:28.667636 mmap(0x7f7013f78000, 13504, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f7013f78000 <0.000036>
06:06:28.667740 close(3</lib/x86_64-linux-gnu/libpthread-2.28.so>) = 0 <0.000029>
06:06:28.667830 openat(AT_FDCWD, "/lib/x86_64-linux-gnu/libgcc_s.so.1", O_RDONLY|O_CLOEXEC) = 3</lib/x86_64-linux-gnu/libgcc_s.so.1> <0.000033>
06:06:28.667925 read(3</lib/x86_64-linux-gnu/libgcc_s.so.1>, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\3402\0\0\0\0\0\0"..., 832) = 832 <0.000029>
06:06:28.668010 fstat(3</lib/x86_64-linux-gnu/libgcc_s.so.1>, {st_mode=S_IFREG|0644, st_size=100712, ...}) = 0 <0.000028>
06:06:28.668097 mmap(NULL, 103472, PROT_READ, MAP_PRIVATE|MAP_DENYWRITE, 3</lib/x86_64-linux-gnu/libgcc_s.so.1>, 0) = 0x7f7013f41000 <0.000031>
06:06:28.668181 mprotect(0x7f7013f44000, 86016, PROT_NONE) = 0 <0.000031>
06:06:28.668256 mmap(0x7f7013f44000, 69632, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3</lib/x86_64-linux-gnu/libgcc_s.so.1>, 0x3000) = 0x7f7013f44000 <0.000034>
06:06:28.668346 mmap(0x7f7013f55000, 12288, PROT_READ, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3</lib/x86_64-linux-gnu/libgcc_s.so.1>, 0x14000) = 0x7f7013f55000 <0.000034>
06:06:28.668435 mmap(0x7f7013f59000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3</lib/x86_64-linux-gnu/libgcc_s.so.1>, 0x17000) = 0x7f7013f59000 <0.000033>
06:06:28.668532 close(3</lib/x86_64-linux-gnu/libgcc_s.so.1>) = 0 <0.000028>
06:06:28.668617 openat(AT_FDCWD, "/lib/x86_64-linux-gnu/libc.so.6", O_RDONLY|O_CLOEXEC) = 3</lib/x86_64-linux-gnu/libc-2.28.so> <0.000034>
06:06:28.668717 read(3</lib/x86_64-linux-gnu/libc-2.28.so>, "\177ELF\2\1\1\3\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\260A\2\0\0\0\0\0"..., 832) = 832 <0.000038>
06:06:28.668832 fstat(3</lib/x86_64-linux-gnu/libc-2.28.so>, {st_mode=S_IFREG|0755, st_size=1824496, ...}) = 0 <0.000031>
06:06:28.668923 mmap(NULL, 1837056, PROT_READ, MAP_PRIVATE|MAP_DENYWRITE, 3</lib/x86_64-linux-gnu/libc-2.28.so>, 0) = 0x7f7013d80000 <0.000031>
06:06:28.669008 mprotect(0x7f7013da2000, 1658880, PROT_NONE) = 0 <0.000033>
06:06:28.669084 mmap(0x7f7013da2000, 1343488, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3</lib/x86_64-linux-gnu/libc-2.28.so>, 0x22000) = 0x7f7013da2000 <0.000035>
06:06:28.669175 mmap(0x7f7013eea000, 311296, PROT_READ, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3</lib/x86_64-linux-gnu/libc-2.28.so>, 0x16a000) = 0x7f7013eea000 <0.000033>
06:06:28.669262 mmap(0x7f7013f37000, 24576, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3</lib/x86_64-linux-gnu/libc-2.28.so>, 0x1b6000) = 0x7f7013f37000 <0.000033>
06:06:28.669356 mmap(0x7f7013f3d000, 14336, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f7013f3d000 <0.000032>
06:06:28.669442 close(3</lib/x86_64-linux-gnu/libc-2.28.so>) = 0 <0.000028>
06:06:28.669543 mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f7013d7e000 <0.000030>
06:06:28.669630 mmap(NULL, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f7013d7b000 <0.000029>
06:06:28.669713 arch_prctl(ARCH_SET_FS, 0x7f7013d7b800) = 0 <0.000038>
06:06:28.669880 mprotect(0x7f7013f37000, 16384, PROT_READ) = 0 <0.000033>
06:06:28.669964 mprotect(0x7f7013f59000, 4096, PROT_READ) = 0 <0.000030>
06:06:28.670045 mprotect(0x7f7013f76000, 4096, PROT_READ) = 0 <0.000030>
06:06:28.670123 mprotect(0x7f7013f84000, 4096, PROT_READ) = 0 <0.000030>
06:06:28.670203 mprotect(0x7f7013f89000, 4096, PROT_READ) = 0 <0.000030>
06:06:28.670279 mprotect(0x7f70141a7000, 4096, PROT_READ) = 0 <0.000031>
06:06:28.670433 mprotect(0x55717f06d000, 32768, PROT_READ) = 0 <0.000045>
06:06:28.670543 mprotect(0x7f70141d8000, 4096, PROT_READ) = 0 <0.000035>
06:06:28.670626 munmap(0x7f70141ab000, 23306) = 0 <0.000036>
06:06:28.670713 set_tid_address(0x7f7013d7bad0) = 26125 <0.000032>
06:06:28.670818 set_robust_list(0x7f7013d7bae0, 24) = 0 <0.000028>
06:06:28.670901 rt_sigaction(SIGRTMIN, {sa_handler=0x7f7013f616b0, sa_mask=[], sa_flags=SA_RESTORER|SA_SIGINFO, sa_restorer=0x7f7013f6d730}, NULL, 8) = 0 <0.000030>
06:06:28.671004 rt_sigaction(SIGRT_1, {sa_handler=0x7f7013f61740, sa_mask=[], sa_flags=SA_RESTORER|SA_RESTART|SA_SIGINFO, sa_restorer=0x7f7013f6d730}, NULL, 8) = 0 <0.000029>
06:06:28.671084 rt_sigprocmask(SIG_UNBLOCK, [RTMIN RT_1], NULL, 8) = 0 <0.000028>
06:06:28.671170 prlimit64(0, RLIMIT_STACK, NULL, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0 <0.000029>
06:06:28.671327 rt_sigaction(SIGPIPE, {sa_handler=SIG_IGN, sa_mask=[PIPE], sa_flags=SA_RESTORER|SA_RESTART, sa_restorer=0x7f7013db7840}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=0}, 8) = 0 <0.000030>
06:06:28.671460 brk(NULL)               = 0x55717fd35000 <0.000030>
06:06:28.671538 brk(0x55717fd56000)     = 0x55717fd56000 <0.000029>
06:06:28.671645 openat(AT_FDCWD, "/proc/self/maps", O_RDONLY|O_CLOEXEC) = 3</proc/26125/maps> <0.000062>
06:06:28.671777 prlimit64(0, RLIMIT_STACK, NULL, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0 <0.000046>
06:06:28.671883 fstat(3</proc/26125/maps>, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0 <0.000047>
06:06:28.671995 read(3</proc/26125/maps>, "55717ef70000-55717ef7e000 r--p 0"..., 1024) = 1024 <0.000056>
06:06:28.672126 read(3</proc/26125/maps>, "_64-linux-gnu/libc-2.28.so\n7f701"..., 1024) = 1024 <0.000037>
06:06:28.672245 read(3</proc/26125/maps>, "x86_64-linux-gnu/libpthread-2.28"..., 1024) = 1024 <0.000036>
06:06:28.672360 read(3</proc/26125/maps>, "8647                      /lib/x"..., 1024) = 1024 <0.000035>
06:06:28.672458 read(3</proc/26125/maps>, "nux-gnu/libz.so.1.2.11\n7f70141a9"..., 1024) = 976 <0.000035>
06:06:28.672559 close(3</proc/26125/maps>) = 0 <0.000032>
06:06:28.672649 sched_getaffinity(26125, 32, [0]) = 8 <0.000028>
06:06:28.672735 rt_sigaction(SIGSEGV, NULL, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=0}, 8) = 0 <0.000028>
06:06:28.672813 rt_sigaction(SIGSEGV, {sa_handler=0x55717f012ad0, sa_mask=[], sa_flags=SA_RESTORER|SA_ONSTACK|SA_SIGINFO, sa_restorer=0x7f7013f6d730}, NULL, 8) = 0 <0.000028>
06:06:28.672892 rt_sigaction(SIGBUS, NULL, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=0}, 8) = 0 <0.000027>
06:06:28.672967 rt_sigaction(SIGBUS, {sa_handler=0x55717f012ad0, sa_mask=[], sa_flags=SA_RESTORER|SA_ONSTACK|SA_SIGINFO, sa_restorer=0x7f7013f6d730}, NULL, 8) = 0 <0.000027>
06:06:28.673045 sigaltstack(NULL, {ss_sp=NULL, ss_flags=SS_DISABLE, ss_size=0}) = 0 <0.000030>
06:06:28.673139 mmap(NULL, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f70141ae000 <0.000031>
06:06:28.673218 mprotect(0x7f70141ae000, 4096, PROT_NONE) = 0 <0.000030>
06:06:28.673292 sigaltstack({ss_sp=0x7f70141af000, ss_flags=0, ss_size=8192}, NULL) = 0 <0.000031>
06:06:28.673419 getrandom("\x7d\x3d\x10\x4b\x6d\x7d\x78\x0a\x5b\x47\x0c\x40\x4f\x5e\x19\xf7", 16, GRND_NONBLOCK) = 16 <0.000030>
06:06:28.673542 openat(AT_FDCWD, "/etc/localtime", O_RDONLY|O_CLOEXEC) = 3</usr/share/zoneinfo/Australia/ACT> <0.000039>
06:06:28.673650 fstat(3</usr/share/zoneinfo/Australia/ACT>, {st_mode=S_IFREG|0644, st_size=2223, ...}) = 0 <0.000030>
06:06:28.673740 fstat(3</usr/share/zoneinfo/Australia/ACT>, {st_mode=S_IFREG|0644, st_size=2223, ...}) = 0 <0.000030>
06:06:28.673864 read(3</usr/share/zoneinfo/Australia/ACT>, "TZif2\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\5\0\0\0\5\0\0\0\0"..., 2560) = 2223 <0.000021>
06:06:28.673957 lseek(3</usr/share/zoneinfo/Australia/ACT>, -1415, SEEK_CUR) = 808 <0.000009>
06:06:28.674001 read(3</usr/share/zoneinfo/Australia/ACT>, "TZif2\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\5\0\0\0\5\0\0\0\0"..., 2560) = 1415 <0.000028>
06:06:28.674086 close(3</usr/share/zoneinfo/Australia/ACT>) = 0 <0.000029>
06:06:28.674177 mmap(NULL, 4198400, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f701397a000 <0.000030>
06:06:28.674266 getpid()                = 26125 <0.000027>
06:06:28.674339 write(1</dev/pts/3<char 136:3>>, "# LogReader: 26125\n", 19# LogReader: 26125
) = 19 <0.000045>
06:06:28.674448 write(1</dev/pts/3<char 136:3>>, "# Query options\n", 16# Query options
) = 16 <0.000033>
06:06:28.674544 write(1</dev/pts/3<char 136:3>>, "# Start: 2020-05-01 00:00:00 (15"..., 42# Start: 2020-05-01 00:00:00 (1588291200)
) = 42 <0.000033>
06:06:28.674635 write(1</dev/pts/3<char 136:3>>, "# End: 2020-10-09 06:06:28 (1602"..., 40# End: 2020-10-09 06:06:28 (1602223588)
) = 40 <0.000033>
06:06:28.674723 write(1</dev/pts/3<char 136:3>>, "# End Query Options\n\n", 21# End Query Options

) = 21 <0.000032>
06:06:28.674809 openat(AT_FDCWD, "/var/log/syslog", O_RDONLY|O_CLOEXEC) = 3</var/log/syslog> <0.000034>
06:06:28.674919 fcntl(3</var/log/syslog>, F_GETFD) = 0x1 (flags FD_CLOEXEC) <0.000029>
06:06:28.675011 read(3</var/log/syslog>, "Nov 22 22:07:55 pmg6 systemd-sys"..., 8192) = 8192 <0.003459>
06:06:28.678568 close(3</var/log/syslog>) = 0 <0.000032>
06:06:28.678683 openat(AT_FDCWD, "/var/log/syslog.1", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory) <0.000034>
06:06:28.678794 openat(AT_FDCWD, "/var/log/syslog", O_RDONLY|O_CLOEXEC) = 3</var/log/syslog> <0.000034>
06:06:28.678897 read(3</var/log/syslog>, "Nov 22 22:07:55 pmg6 systemd-sys"..., 8192) = 8192 <0.000038>
06:06:28.679002 close(3</var/log/syslog>) = 0 <0.000031>
06:06:28.679091 munmap(0x7f701397a000, 4198400) = 0 <0.000041>
06:06:28.679180 sigaltstack({ss_sp=NULL, ss_flags=SS_DISABLE, ss_size=8192}, NULL) = 0 <0.000028>
06:06:28.679258 munmap(0x7f70141ae000, 12288) = 0 <0.000030>
06:06:28.679351 exit_group(0)           = ?
06:06:28.679555 +++ exited with 0 +++

Stoiko Ivanov · Oct 9, 2020

That explains it ... :

Code:

06:06:28.674919 fcntl(3</var/log/syslog>, F_GETFD) = 0x1 (flags FD_CLOEXEC) <0.000029>
06:06:28.675011 read(3</var/log/syslog>, "Nov 22 22:07:55 pmg6 systemd-sys"..., 8192) = 8192 <0.003459>
06:06:28.678568 close(3</var/log/syslog>) = 0 <0.000032>
06:06:28.678683 openat(AT_FDCWD, "/var/log/syslog.1", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory) <0.000034>

* the file /var/log/syslog contains entries from Nov 22 (which i would assume mean from roughly 11 months ago).
* the log-tracker detects which files to track by looking at the earliest timestamp (the top of the file) (here this would be to old), and then at the earliest timestamp of the next file (syslog.1), which does not exist - therefore it decides that the logs are too old and does not return anything

(you can try by trying to look at the logs from last November until today...)

It seems that your container does not run logrotate successfully (and pmg-log-tracker would need that in order to function)

-> check your logrotate settings

I hope this helps!

Craig Tosi · Oct 9, 2020

Ahhhh, so this is relevant then:-

https://forum.proxmox.com/threads/logrotate-issue-in-buster-lxc.56726/

Stoiko Ivanov · Oct 12, 2020

Craig Tosi said:
Ahhhh, so this is relevant then:-

yes - sorry did not think to mention that nesting needs to be enabled for an unprivileged container

did you manage to resolve the issue?

Craig Tosi · Oct 13, 2020

Yes, issue resolved thank you. But I'm not sure the nesting feature is required only on unprivileged containers. I have 1 of each and I had to turn on nesting even for the privileged container to resolve the issue.

Stoiko Ivanov · Oct 13, 2020

Craig Tosi said:
Yes, issue resolved thank you. But I'm not sure the nesting feature is required only on unprivileged containers. I have 1 of each and I had to turn on nesting even for the privileged container to resolve the issue.

hm - TBH I did not explicitly try it with a privileged container.
Enabling nesting with a privileged container does disable quite a few of the isolation mechanisms baked into lxc (and pve's use of it) - so if possible I would suggest to stick with an unprivileged container for security reasons

Search

Search

Tracking Center not working in PMG lxc container [SOLVED]

Craig Tosi

Active Member

t.lamprecht

Proxmox Staff Member

Craig Tosi

Active Member

Stoiko Ivanov

Proxmox Staff Member

Craig Tosi

Active Member

Stoiko Ivanov

Proxmox Staff Member

Craig Tosi

Active Member

Stoiko Ivanov

Proxmox Staff Member

Craig Tosi

Active Member

Stoiko Ivanov

Proxmox Staff Member

Craig Tosi

Active Member

Stoiko Ivanov

Proxmox Staff Member

Craig Tosi

Active Member

Stoiko Ivanov

Proxmox Staff Member