Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

rsyslog apparmor denial on reading /proc/sys/net/ipv6/conf/all/disable_ipv6 #6223

Open
martinpitt opened this issue Apr 16, 2024 · 2 comments

Comments

@martinpitt
Copy link
Member

Downstream report: https://launchpad.net/bugs/2061726

@cockpituous
Copy link
Contributor

cockpituous commented Apr 16, 2024

ubuntu-stable
Ooops, it happened again


# ----------------------------------------------------------------------
# testCreate (__main__.TestTimers.testCreate)
Starting ChromeDriver 127.0.6533.99 (f31af5097d90ef5ae5bd7b8700199bc6189ba34d-refs/branch-heads/6533@{#1910}) on port 35415
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully.
Synchronizing state of pmie.service with SysV service script with /usr/lib/systemd/systemd-sysv-install.
Executing: /usr/lib/systemd/systemd-sysv-install disable pmie
Synchronizing state of pmlogger.service with SysV service script with /usr/lib/systemd/systemd-sysv-install.
Executing: /usr/lib/systemd/systemd-sysv-install disable pmlogger
kex_exchange_identification: read: Connection reset by peer
> info: REAL SOCKET
> info: CONNECTING local
> info: HIDDEN HINT cockpit1:localhost/system/services false
> info: SHELL {"hash":"","host":"localhost","path":"system/services"} localhost null
> info: NEW IFRAME cockpit1:localhost/updates
> info: SRC cockpit1:localhost/updates null -> ../../@localhost/updates/index.html#/
> info: NEW IFRAME cockpit1:localhost/system
> info: SRC cockpit1:localhost/system null -> ../../@localhost/system/index.html#/
> info: NEW IFRAME cockpit1:localhost/system/services
> info: SRC cockpit1:localhost/system/services null -> ../../@localhost/system/services.html#/
> info: NEW IFRAME cockpit1:localhost/playground/preloaded
> info: SRC cockpit1:localhost/playground/preloaded null -> ../../@localhost/playground/preloaded.html#/
> info: SHELL {"hash":"","host":"localhost","path":"system/services"} localhost null
> info: PARENT SOCKET
> info: PARENT SOCKET
> info: FRAME INITIALIZED cockpit1:localhost/updates
> info: HIDDEN HINT cockpit1:localhost/updates true
> info: PARENT SOCKET
> info: FRAME INITIALIZED cockpit1:localhost/system
> info: HIDDEN HINT cockpit1:localhost/system true
> info: SHELL {"hash":"","host":"localhost","path":"system/services"} localhost null
> info: PARENT SOCKET
> info: SHELL {"hash":"","host":"localhost","path":"system/services"} localhost null
> info: FRAME INITIALIZED cockpit1:localhost/system/services
> info: HIDDEN HINT cockpit1:localhost/system/services false
> info: SHELL {"hash":"","host":"localhost","path":"system/services"} localhost null
> info: FRAME INITIALIZED cockpit1:localhost/playground/preloaded
> info: HIDDEN HINT cockpit1:localhost/playground/preloaded true
> info: SHELL {"hash":"","host":"localhost","path":"system/services"} localhost null
> info: SHELL {"hash":"","host":"localhost","path":"system/services"} localhost null
> info: SHELL {"hash":"","host":"localhost","path":"system/services"} localhost null
> info: SHELL {"hash":"","host":"localhost","path":"system/services"} localhost null
> info: SHELL {"hash":"/?type=timer","host":"localhost","path":"system/services"} localhost null
> info: SRC cockpit1:localhost/system/services ../../@localhost/system/services.html#/ -> ../../@localhost/system/services.html#/?type=timer
> info: SHELL {"hash":"/?type=timer","host":"localhost","path":"system/services"} localhost null
> info: Object(4)
> info: Object(4)
> info: Object(4)
> info: Object(4)
> error: Text input: Text input requires either an id or aria-label to be specified
> error: Text input: Text input requires either an id or aria-label to be specified
> info: Object(4)
> info: Object(4)
> info: Object(4)
> error: Text input: Text input requires either an id or aria-label to be specified
> info: Object(4)
> warn: transport closed: disconnected
> info: SHELL {"hash":"/?type=timer","host":"localhost","path":"system/services"} localhost disconnected
kex_exchange_identification: read: Connection reset by peer
audit: type=1400 audit(2091412800.282:146): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=751 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
audit: type=1400 audit(2091412800.282:147): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=751 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
Wrote JS log to TestTimers-testCreate-ubuntu-stable-127.0.0.2-2901-FAIL.js.log
Journal extracted to TestTimers-testCreate-ubuntu-stable-127.0.0.2-2901-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/common/testlib.py", line 1925, in tearDown
    self.check_journal_messages()
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2186, in check_journal_messages
    raise Error(UNEXPECTED_MESSAGE + "journal messages:\n" + first)
testlib.Error: FAIL: Test completed, but found unexpected journal messages:
audit: type=1400 audit(2091412800.282:146): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=751 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0

# Result testCreate (__main__.TestTimers.testCreate) failed
# 1 TEST FAILED [171s on e79f38b95284]
not ok 128 test/verify/check-system-services TestTimers.testCreate

First occurrence: 2024-10-03T09:25:54.564074+00:00 | revision d4b329725a728a27c263587a8ea1670edd66c484
Times recorded: 1
Latest occurrences:

  • 2024-10-03T09:25:54.564074+00:00 | revision d4b329725a728a27c263587a8ea1670edd66c484

# ----------------------------------------------------------------------
# testCreate (__main__.TestTimers.testCreate)
Starting ChromeDriver 127.0.6533.99 (f31af5097d90ef5ae5bd7b8700199bc6189ba34d-refs/branch-heads/6533@{#1910}) on port 47323
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully.
Synchronizing state of pmie.service with SysV service script with /usr/lib/systemd/systemd-sysv-install.
Executing: /usr/lib/systemd/systemd-sysv-install disable pmie
Synchronizing state of pmlogger.service with SysV service script with /usr/lib/systemd/systemd-sysv-install.
Executing: /usr/lib/systemd/systemd-sysv-install disable pmlogger
kex_exchange_identification: read: Connection reset by peer
> info: Object(4)
> info: Object(4)
> info: Object(4)
> info: Object(4)
> error: Text input: Text input requires either an id or aria-label to be specified
> error: Text input: Text input requires either an id or aria-label to be specified
> info: Object(4)
> info: Object(4)
> info: Object(4)
> error: Text input: Text input requires either an id or aria-label to be specified
> info: Object(4)
> warn: transport closed: disconnected
kex_exchange_identification: read: Connection reset by peer
audit: type=1400 audit(2082814212.433:146): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=735 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
audit: type=1400 audit(2082814212.433:147): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=735 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
Wrote JS log to TestTimers-testCreate-ubuntu-stable-127.0.0.2-2901-FAIL.js.log
Journal extracted to TestTimers-testCreate-ubuntu-stable-127.0.0.2-2901-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/common/testlib.py", line 1911, in tearDown
    self.check_journal_messages()
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2172, in check_journal_messages
    raise Error(UNEXPECTED_MESSAGE + "journal messages:\n" + first)
testlib.Error: FAIL: Test completed, but found unexpected journal messages:
audit: type=1400 audit(2082814212.433:146): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=735 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0

# Result testCreate (__main__.TestTimers.testCreate) failed
# 1 TEST FAILED [156s on 05871a3341d9]
not ok 129 test/verify/check-system-services TestTimers.testCreate

First occurrence: 2024-10-07T07:42:17.865642+00:00 | revision b67c4b2b928137dd8bc660d2feb9e0d079a84aad
Times recorded: 1
Latest occurrences:

  • 2024-10-07T07:42:17.865642+00:00 | revision b67c4b2b928137dd8bc660d2feb9e0d079a84aad

# ----------------------------------------------------------------------
# testTime (__main__.TestSystemInfoTime.testTime)
Starting ChromeDriver 127.0.6533.99 (f31af5097d90ef5ae5bd7b8700199bc6189ba34d-refs/branch-heads/6533@{#1910}) on port 42415
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully.
Failed to kill unit packagekit.service: Unit packagekit.service not loaded.
Failed to reset failed state of unit packagekit.service: Unit packagekit.service not loaded.
> warn: cockpit.format_{bytes,bits}[_per_sec](..., GiB, [object Object]) is deprecated.
> info: Object(4)
> info: Object(4)
> info: Object(4)
audit: type=1400 audit(2116389789.831:146): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=741 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
audit: type=1400 audit(2116389789.831:147): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=741 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
Wrote JS log to TestSystemInfoTime-testTime-ubuntu-stable-127.0.0.2-2901-FAIL.js.log
Journal extracted to TestSystemInfoTime-testTime-ubuntu-stable-127.0.0.2-2901-FAIL.log.gz
> info: PackageKit went away from D-Bus
> info: PackageKit went away from D-Bus
> info: PackageKit went away from D-Bus
> info: PackageKit went away from D-Bus
Failed to reset failed state of unit packagekit.service: Unit packagekit.service not loaded.
> info: Object(4)
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/common/testlib.py", line 1911, in tearDown
    self.check_journal_messages()
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2172, in check_journal_messages
    raise Error(UNEXPECTED_MESSAGE + "journal messages:\n" + first)
testlib.Error: FAIL: Test completed, but found unexpected journal messages:
audit: type=1400 audit(2116389789.831:146): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=741 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0

# Result testTime (__main__.TestSystemInfoTime.testTime) failed
# 1 TEST FAILED [53s on 4cfd7e9cdab2]
not ok 72 test/verify/check-system-info TestSystemInfoTime.testTime

First occurrence: 2024-10-07T11:00:08.283892+00:00 | revision b1511a7ecd9cfd9920252f59e87b0d4c8c733f35
Times recorded: 1
Latest occurrences:

  • 2024-10-07T11:00:08.283892+00:00 | revision b1511a7ecd9cfd9920252f59e87b0d4c8c733f35

# ----------------------------------------------------------------------
# testCreate (__main__.TestTimers.testCreate)
Starting ChromeDriver 127.0.6533.99 (f31af5097d90ef5ae5bd7b8700199bc6189ba34d-refs/branch-heads/6533@{#1910}) on port 44953
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully.
Synchronizing state of pmie.service with SysV service script with /usr/lib/systemd/systemd-sysv-install.
Executing: /usr/lib/systemd/systemd-sysv-install disable pmie
Synchronizing state of pmlogger.service with SysV service script with /usr/lib/systemd/systemd-sysv-install.
Executing: /usr/lib/systemd/systemd-sysv-install disable pmlogger
> info: Object(4)
> info: Object(4)
> info: Object(4)
> info: Object(4)
> error: Text input: Text input requires either an id or aria-label to be specified
> error: Text input: Text input requires either an id or aria-label to be specified
> info: Object(4)
> info: Object(4)
> info: Object(4)
> error: Text input: Text input requires either an id or aria-label to be specified
> info: Object(4)
> warn: transport closed: disconnected
kex_exchange_identification: read: Connection reset by peer
audit: type=1400 audit(2082816605.625:146): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=758 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
audit: type=1400 audit(2082816605.627:147): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=758 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
Wrote JS log to TestTimers-testCreate-ubuntu-stable-127.0.0.2-3001-FAIL.js.log
Journal extracted to TestTimers-testCreate-ubuntu-stable-127.0.0.2-3001-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/common/testlib.py", line 1911, in tearDown
    self.check_journal_messages()
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2172, in check_journal_messages
    raise Error(UNEXPECTED_MESSAGE + "journal messages:\n" + first)
testlib.Error: FAIL: Test completed, but found unexpected journal messages:
audit: type=1400 audit(2082816605.625:146): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=758 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0

# Result testCreate (__main__.TestTimers.testCreate) failed
# 1 TEST FAILED [172s on 354797d59dc2]
not ok 115 test/verify/check-system-services TestTimers.testCreate

First occurrence: 2024-10-08T09:45:43.293720+00:00 | revision 2c46a08588436524cbe4fbb5265973a151729e6a
Times recorded: 1
Latest occurrences:

  • 2024-10-08T09:45:43.293720+00:00 | revision 2c46a08588436524cbe4fbb5265973a151729e6a

# ----------------------------------------------------------------------
# testCreate (__main__.TestTimers.testCreate)
Starting ChromeDriver 127.0.6533.99 (f31af5097d90ef5ae5bd7b8700199bc6189ba34d-refs/branch-heads/6533@{#1910}) on port 48797
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully.
Synchronizing state of pmie.service with SysV service script with /usr/lib/systemd/systemd-sysv-install.
Executing: /usr/lib/systemd/systemd-sysv-install disable pmie
Synchronizing state of pmlogger.service with SysV service script with /usr/lib/systemd/systemd-sysv-install.
Executing: /usr/lib/systemd/systemd-sysv-install disable pmlogger
> info: Object(4)
> info: Object(4)
> info: Object(4)
> info: Object(4)
> error: Text input: Text input requires either an id or aria-label to be specified
> error: Text input: Text input requires either an id or aria-label to be specified
> info: Object(4)
> info: Object(4)
> info: Object(4)
> error: Text input: Text input requires either an id or aria-label to be specified
> info: Object(4)
> warn: transport closed: disconnected
kex_exchange_identification: read: Connection reset by peer
audit: type=1400 audit(2082816600.423:146): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=751 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
audit: type=1400 audit(2082816600.423:147): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=751 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
audit: type=1400 audit(2091413400.266:148): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=751 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
audit: type=1400 audit(2091413400.267:149): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=751 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
Wrote JS log to TestTimers-testCreate-ubuntu-stable-127.0.0.2-2401-FAIL.js.log
Journal extracted to TestTimers-testCreate-ubuntu-stable-127.0.0.2-2401-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/common/testlib.py", line 1911, in tearDown
    self.check_journal_messages()
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2172, in check_journal_messages
    raise Error(UNEXPECTED_MESSAGE + "journal messages:\n" + first)
testlib.Error: FAIL: Test completed, but found unexpected journal messages:
audit: type=1400 audit(2082816600.423:146): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=751 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0

# Result testCreate (__main__.TestTimers.testCreate) failed
# 1 TEST FAILED [159s on 993a24b0db7c]
not ok 111 test/verify/check-system-services TestTimers.testCreate

First occurrence: 2024-10-10T10:45:56.148376+00:00 | revision cd233246150eb7c8d4ec0bce88d987537c4f42c9
Times recorded: 1
Latest occurrences:

  • 2024-10-10T10:45:56.148376+00:00 | revision cd233246150eb7c8d4ec0bce88d987537c4f42c9

# ----------------------------------------------------------------------
# testEvents (__main__.TestHistoryMetrics.testEvents)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 54707
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 54707.
Unit [email protected] does not exist, proceeding anyway.
Unit [email protected] does not exist, proceeding anyway.
Created symlink '/etc/systemd/system/[email protected]' → '/dev/null'.
Created symlink '/etc/systemd/system/[email protected]' → '/dev/null'.
> warn: CPU temperature metric closed: Object(3)
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2020-08-31 07:42:26 is 1186937.832753236 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/pmlogger.log: time stamp 2020-09-01 08:59:46 is 1277977.831548375 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.index: time stamp 2020-09-01 09:07:46 is 1278457.831346106 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.meta: time stamp 2020-09-01 09:07:46 is 1278457.828761961 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.0: time stamp 2020-09-01 09:08:16 is 1278487.809477427 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/Latest: time stamp 2020-09-01 08:59:46 is 1277977.809296327 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain: time stamp 2020-09-01 08:59:46 is 1277977.808221712 s in the future
tar: var/log/pcp/pmlogger: time stamp 2020-09-01 09:07:29 is 1278440.808177499 s in the future
> warn: CPU temperature metric closed: Object(3)
tar: var/log/pcp/pmlogger/pmlogger_check.log.prev: time stamp 2020-09-16 06:44:13 is 1320247.316944397 s in the future
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2020-09-11 01:58:10 is 871084.315177602 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.meta: time stamp 2020-09-16 06:56:12 is 1320966.308108018 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/pmlogger.log: time stamp 2020-09-16 06:56:19 is 1320973.305770713 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.0: time stamp 2020-09-16 06:56:19 is 1320973.26749845 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.index: time stamp 2020-09-16 06:56:19 is 1320973.266207267 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/Latest: time stamp 2020-09-16 06:44:12 is 1320246.266142917 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain: time stamp 2020-09-16 06:44:13 is 1320247.265565664 s in the future
tar: var/log/pcp/pmlogger: time stamp 2020-09-16 07:20:59 is 1322453.265534445 s in the future
tar: var/log/pcp: time stamp 2020-09-16 07:20:50 is 1322444.265517523 s in the future
tar: var/log: time stamp 2020-09-16 07:20:50 is 1322444.265491665 s in the future
tar: var: time stamp 2020-09-16 07:20:50 is 1322444.265469954 s in the future
> warn: CPU temperature metric closed: Object(3)
> warn: CPU temperature metric closed: Object(3)
> warn: CPU temperature metric closed: Object(3)
tar: var/log/pcp/pmlogger/m1.cockpit.lan/Latest: time stamp 2021-03-08 10:36:24 is 14649103.004340302 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.meta: time stamp 2021-03-08 10:41:24 is 14649403.000388738 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.0: time stamp 2021-03-08 10:43:24 is 14649522.996175794 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.index: time stamp 2021-03-08 10:41:24 is 14649402.996050699 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/pmlogger.log: time stamp 2021-03-08 10:36:24 is 14649102.994379394 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan: time stamp 2021-03-08 10:36:24 is 14649102.99428682 s in the future
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2021-02-25 07:49:03 is 13688661.994189798 s in the future
tar: var/log/pcp/pmlogger/pmlogger_check.log.prev: time stamp 2021-03-08 10:36:24 is 14649102.99406835 s in the future
tar: var/log/pcp/pmlogger: time stamp 2021-03-08 10:36:54 is 14649132.994018577 s in the future
> warn: CPU temperature metric closed: Object(3)
> warn: CPU temperature metric closed: Object(3)
> warn: CPU temperature metric closed: Object(3)
audit: type=1400 audit(1600550675.070:146): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=735 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
audit: type=1400 audit(1600550675.071:147): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=735 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
Wrote JS log to TestHistoryMetrics-testEvents-ubuntu-stable-127.0.0.2-2501-FAIL.js.log
Journal extracted to TestHistoryMetrics-testEvents-ubuntu-stable-127.0.0.2-2501-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/common/testlib.py", line 1911, in tearDown
    self.check_journal_messages()
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2172, in check_journal_messages
    raise Error(UNEXPECTED_MESSAGE + "journal messages:\n" + first)
testlib.Error: FAIL: Test completed, but found unexpected journal messages:
audit: type=1400 audit(1600550675.070:146): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=735 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0

# Result testEvents (__main__.TestHistoryMetrics.testEvents) failed
# 1 TEST FAILED [67s on c97a56214b37]
not ok 26 test/verify/check-metrics TestHistoryMetrics.testEvents

First occurrence: 2024-10-10T10:55:25.160750+00:00 | revision 0f79b0eaa1c9a69f622849de873b9474a0f71275
Times recorded: 1
Latest occurrences:

  • 2024-10-10T10:55:25.160750+00:00 | revision 0f79b0eaa1c9a69f622849de873b9474a0f71275

# ----------------------------------------------------------------------
# testCreate (__main__.TestTimers.testCreate)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 50573
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 50573.
Synchronizing state of pmie.service with SysV service script with /usr/lib/systemd/systemd-sysv-install.
Executing: /usr/lib/systemd/systemd-sysv-install disable pmie
Synchronizing state of pmlogger.service with SysV service script with /usr/lib/systemd/systemd-sysv-install.
Executing: /usr/lib/systemd/systemd-sysv-install disable pmlogger
> info: Object(4)
> info: Object(4)
> info: Object(4)
> info: Object(4)
> error: Text input: Text input requires either an id or aria-label to be specified
> error: Text input: Text input requires either an id or aria-label to be specified
> info: Object(4)
> info: Object(4)
> info: Object(4)
> error: Text input: Text input requires either an id or aria-label to be specified
> info: Object(4)
> warn: transport closed: disconnected
kex_exchange_identification: read: Connection reset by peer
audit: type=1400 audit(2088720000.450:146): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=743 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
audit: type=1400 audit(2088720000.450:147): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=743 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
Wrote JS log to TestTimers-testCreate-ubuntu-stable-127.0.0.2-2401-FAIL.js.log
Journal extracted to TestTimers-testCreate-ubuntu-stable-127.0.0.2-2401-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/common/testlib.py", line 1911, in tearDown
    self.check_journal_messages()
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2172, in check_journal_messages
    raise Error(UNEXPECTED_MESSAGE + "journal messages:\n" + first)
testlib.Error: FAIL: Test completed, but found unexpected journal messages:
audit: type=1400 audit(2088720000.450:146): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=743 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0

# Result testCreate (__main__.TestTimers.testCreate) failed
# 1 TEST FAILED [160s on bdcb73079ec0]
not ok 131 test/verify/check-system-services TestTimers.testCreate

First occurrence: 2024-10-10T13:39:22.803143+00:00 | revision 06995fd08e25dad2f7f5e6edc187f41823221cad
Times recorded: 1
Latest occurrences:

  • 2024-10-10T13:39:22.803143+00:00 | revision 06995fd08e25dad2f7f5e6edc187f41823221cad

# ----------------------------------------------------------------------
# testCPUSecurityMitigationsEnable (__main__.TestSystemInfo.testCPUSecurityMitigationsEnable)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 57795
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 57795.
> error: Switch: Switch requires either a label or an aria-label to be specified
Connection to 127.0.0.2 closed by remote host.
> warn: transport closed: disconnected
kex_exchange_identification: read: Connection reset by peer
Sourcing file `/etc/default/grub'
Sourcing file `/etc/default/grub.d/50-cloudimg-settings.cfg'
Generating grub configuration file ...
Found linux image: /boot/vmlinuz-42.0.0
Found linux image: /boot/vmlinuz-6.11.0-8-generic
Found initrd image: /boot/initrd.img-6.11.0-8-generic
Warning: os-prober will not be executed to detect other bootable partitions.
Systems on them will not be added to the GRUB boot configuration.
Check GRUB_DISABLE_OS_PROBER documentation entry.
Adding boot menu entry for UEFI Firmware Settings ...
done
Sourcing file `/etc/default/grub'
Sourcing file `/etc/default/grub.d/50-cloudimg-settings.cfg'
Generating grub configuration file ...
Found linux image: /boot/vmlinuz-6.11.0-8-generic
Found initrd image: /boot/initrd.img-6.11.0-8-generic
Warning: os-prober will not be executed to detect other bootable partitions.
Systems on them will not be added to the GRUB boot configuration.
Check GRUB_DISABLE_OS_PROBER documentation entry.
Adding boot menu entry for UEFI Firmware Settings ...
done
> error: Switch: Switch requires either a label or an aria-label to be specified
> warn: transport closed: disconnected
Sourcing file `/etc/default/grub'
Sourcing file `/etc/default/grub.d/50-cloudimg-settings.cfg'
Generating grub configuration file ...
Found linux image: /boot/vmlinuz-6.11.0-8-generic
Found initrd image: /boot/initrd.img-6.11.0-8-generic
Warning: os-prober will not be executed to detect other bootable partitions.
Systems on them will not be added to the GRUB boot configuration.
Check GRUB_DISABLE_OS_PROBER documentation entry.
Adding boot menu entry for UEFI Firmware Settings ...
done
Sourcing file `/etc/default/grub'
Sourcing file `/etc/default/grub.d/50-cloudimg-settings.cfg'
Generating grub configuration file ...
Found linux image: /boot/vmlinuz-6.11.0-8-generic
Found initrd image: /boot/initrd.img-6.11.0-8-generic
Warning: os-prober will not be executed to detect other bootable partitions.
Systems on them will not be added to the GRUB boot configuration.
Check GRUB_DISABLE_OS_PROBER documentation entry.
Adding boot menu entry for UEFI Firmware Settings ...
done
kex_exchange_identification: read: Connection reset by peer
kex_exchange_identification: read: Connection reset by peer
kex_exchange_identification: read: Connection reset by peer
kex_exchange_identification: read: Connection reset by peer
kex_exchange_identification: read: Connection reset by peer
> error: Switch: Switch requires either a label or an aria-label to be specified
> warn: transport closed: disconnected
kex_exchange_identification: read: Connection reset by peer
> warn: Failed to get dmidecode information:  /sys/firmware/dmi/tables/smbios_entry_point: Permission denied
Can't read memory from /dev/mem
Failed to stop rpm-ostreed.service: Unit rpm-ostreed.service not loaded.
Unit rpm-ostreed.service does not exist, proceeding anyway.
Created symlink '/etc/systemd/system/rpm-ostreed.service' → '/dev/null'.
> error: Switch: Switch requires either a label or an aria-label to be specified
audit: type=1400 audit(1728906060.150:142): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=762 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
audit: type=1400 audit(1728906060.153:143): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=762 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
audit: type=1400 audit(1728906134.395:142): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=744 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
audit: type=1400 audit(1728906134.395:143): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=744 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
audit: type=1400 audit(1728906200.712:142): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=742 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
audit: type=1400 audit(1728906200.713:143): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=742 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
audit: type=1400 audit(1728906265.799:142): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=744 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
audit: type=1400 audit(1728906265.799:143): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=744 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
Wrote JS log to TestSystemInfo-testCPUSecurityMitigationsEnable-ubuntu-stable-127.0.0.2-2201-FAIL.js.log
Journal extracted to TestSystemInfo-testCPUSecurityMitigationsEnable-ubuntu-stable-127.0.0.2-2201-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/common/testlib.py", line 1911, in tearDown
    self.check_journal_messages()
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2172, in check_journal_messages
    raise Error(UNEXPECTED_MESSAGE + "journal messages:\n" + first)
testlib.Error: FAIL: Test completed, but found unexpected journal messages:
audit: type=1400 audit(1728906060.150:142): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=762 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0

# Result testCPUSecurityMitigationsEnable (__main__.TestSystemInfo.testCPUSecurityMitigationsEnable) failed
# 1 TEST FAILED [334s on 04c5fc3d337c]
not ok 68 test/verify/check-system-info TestSystemInfo.testCPUSecurityMitigationsEnable

First occurrence: 2024-10-14T11:44:55.445179+00:00 | revision ec42cb7a95953d1f9c9563f01c2902b93c696eed
Times recorded: 1
Latest occurrences:

  • 2024-10-14T11:44:55.445179+00:00 | revision ec42cb7a95953d1f9c9563f01c2902b93c696eed

# ----------------------------------------------------------------------
# testEvents (__main__.TestHistoryMetrics.testEvents)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 42925
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 42925.
Unit [email protected] does not exist, proceeding anyway.
Unit [email protected] does not exist, proceeding anyway.
Created symlink '/etc/systemd/system/[email protected]' → '/dev/null'.
Created symlink '/etc/systemd/system/[email protected]' → '/dev/null'.
> warn: CPU temperature metric closed: Object(3)
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2020-08-31 07:42:26 is 1186939.143313103 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/pmlogger.log: time stamp 2020-09-01 08:59:46 is 1277979.142912492 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.index: time stamp 2020-09-01 09:07:46 is 1278459.142342053 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.meta: time stamp 2020-09-01 09:07:46 is 1278459.139352634 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.0: time stamp 2020-09-01 09:08:16 is 1278489.121540241 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/Latest: time stamp 2020-09-01 08:59:46 is 1277979.121396832 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain: time stamp 2020-09-01 08:59:46 is 1277979.120592416 s in the future
tar: var/log/pcp/pmlogger: time stamp 2020-09-01 09:07:29 is 1278442.120532934 s in the future
> warn: CPU temperature metric closed: Object(3)
tar: var/log/pcp/pmlogger/pmlogger_check.log.prev: time stamp 2020-09-16 06:44:13 is 1320246.537810712 s in the future
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2020-09-11 01:58:10 is 871083.537214433 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.meta: time stamp 2020-09-16 06:56:12 is 1320965.534601471 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/pmlogger.log: time stamp 2020-09-16 06:56:19 is 1320972.533716072 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.0: time stamp 2020-09-16 06:56:19 is 1320972.51404385 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.index: time stamp 2020-09-16 06:56:19 is 1320972.513250261 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/Latest: time stamp 2020-09-16 06:44:12 is 1320245.513023696 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain: time stamp 2020-09-16 06:44:13 is 1320246.512906135 s in the future
tar: var/log/pcp/pmlogger: time stamp 2020-09-16 07:20:59 is 1322452.512880497 s in the future
tar: var/log/pcp: time stamp 2020-09-16 07:20:50 is 1322443.512864136 s in the future
tar: var/log: time stamp 2020-09-16 07:20:50 is 1322443.512840061 s in the future
tar: var: time stamp 2020-09-16 07:20:50 is 1322443.512819232 s in the future
> warn: CPU temperature metric closed: Object(3)
> warn: CPU temperature metric closed: Object(3)
> warn: CPU temperature metric closed: Object(3)
tar: var/log/pcp/pmlogger/m1.cockpit.lan/Latest: time stamp 2021-03-08 10:36:24 is 14649104.048768483 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.meta: time stamp 2021-03-08 10:41:24 is 14649404.046502973 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.0: time stamp 2021-03-08 10:43:24 is 14649524.044741678 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.index: time stamp 2021-03-08 10:41:24 is 14649404.043813277 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/pmlogger.log: time stamp 2021-03-08 10:36:24 is 14649104.043427373 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan: time stamp 2021-03-08 10:36:24 is 14649104.043253076 s in the future
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2021-02-25 07:49:03 is 13688663.042948655 s in the future
tar: var/log/pcp/pmlogger/pmlogger_check.log.prev: time stamp 2021-03-08 10:36:24 is 14649104.042847405 s in the future
tar: var/log/pcp/pmlogger: time stamp 2021-03-08 10:36:54 is 14649134.042800466 s in the future
> warn: CPU temperature metric closed: Object(3)
> warn: CPU temperature metric closed: Object(3)
> warn: CPU temperature metric closed: Object(3)
audit: type=1400 audit(1615200500.826:146): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=754 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
audit: type=1400 audit(1615200500.827:147): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=754 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
Wrote JS log to TestHistoryMetrics-testEvents-ubuntu-stable-127.0.0.2-2401-FAIL.js.log
Journal extracted to TestHistoryMetrics-testEvents-ubuntu-stable-127.0.0.2-2401-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/common/testlib.py", line 1911, in tearDown
    self.check_journal_messages()
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2172, in check_journal_messages
    raise Error(UNEXPECTED_MESSAGE + "journal messages:\n" + first)
testlib.Error: FAIL: Test completed, but found unexpected journal messages:
audit: type=1400 audit(1615200500.826:146): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=754 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0

# Result testEvents (__main__.TestHistoryMetrics.testEvents) failed
# 1 TEST FAILED [64s on f7c2789c8c50]
not ok 29 test/verify/check-metrics TestHistoryMetrics.testEvents

First occurrence: 2024-10-21T16:29:54.701392+00:00 | revision 63fe54db729ef459fbb480285528036c9ad23592
Times recorded: 1
Latest occurrences:

  • 2024-10-21T16:29:54.701392+00:00 | revision 63fe54db729ef459fbb480285528036c9ad23592

# ----------------------------------------------------------------------
# testCreate (__main__.TestTimers.testCreate)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 45009
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 45009.
Synchronizing state of pmie.service with SysV service script with /usr/lib/systemd/systemd-sysv-install.
Executing: /usr/lib/systemd/systemd-sysv-install disable pmie
Synchronizing state of pmlogger.service with SysV service script with /usr/lib/systemd/systemd-sysv-install.
Executing: /usr/lib/systemd/systemd-sysv-install disable pmlogger
kex_exchange_identification: read: Connection reset by peer
> warn: Failed to get current crypto policy: not-found ; falling back to /etc/crypto-policies/config
> error: TypeError: Cannot read properties of null (reading 'trim')
> info: Object(4)
> info: Object(4)
> info: Object(4)
> info: Object(4)
> error: Text input: Text input requires either an id or aria-label to be specified
> error: Text input: Text input requires either an id or aria-label to be specified
> info: Object(4)
> info: Object(4)
> info: Object(4)
> error: Text input: Text input requires either an id or aria-label to be specified
> info: Object(4)
> warn: transport closed: disconnected
audit: type=1400 audit(2082816600.269:146): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=729 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
audit: type=1400 audit(2082816600.269:147): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=729 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
Wrote JS log to TestTimers-testCreate-ubuntu-stable-127.0.0.2-2601-FAIL.js.log
Journal extracted to TestTimers-testCreate-ubuntu-stable-127.0.0.2-2601-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/common/testlib.py", line 1927, in tearDown
    self.check_journal_messages()
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2190, in check_journal_messages
    raise Error(UNEXPECTED_MESSAGE + "journal messages:\n" + first)
testlib.Error: FAIL: Test completed, but found unexpected journal messages:
audit: type=1400 audit(2082816600.269:146): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=729 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0

# Result testCreate (__main__.TestTimers.testCreate) failed
# 1 TEST FAILED [158s on fdfaf3a03143]
not ok 109 test/verify/check-system-services TestTimers.testCreate

First occurrence: 2024-11-15T12:44:53.811758+00:00 | revision c6c1d4d7630ddc01f55c82ba5be14e8ee0a6dadb
Times recorded: 1
Latest occurrences:

  • 2024-11-15T12:44:53.811758+00:00 | revision c6c1d4d7630ddc01f55c82ba5be14e8ee0a6dadb

@cockpituous
Copy link
Contributor

cockpituous commented Aug 27, 2024

ubuntu-2404
Ooops, it happened again


# ----------------------------------------------------------------------
# testEvents (__main__.TestHistoryMetrics.testEvents)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 47649
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 47649.
Unit [email protected] does not exist, proceeding anyway.
Unit [email protected] does not exist, proceeding anyway.
Created symlink /etc/systemd/system/[email protected] → /dev/null.
Created symlink /etc/systemd/system/[email protected] → /dev/null.
Failed to get triggering units for '[email protected]', ignoring: Invalid argument
Failed to get triggering units for '[email protected]', ignoring: Invalid argument
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2020-08-31 07:42:26 is 1186939.942229531 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/pmlogger.log: time stamp 2020-09-01 08:59:46 is 1277979.941515732 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.index: time stamp 2020-09-01 09:07:46 is 1278459.940990566 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.meta: time stamp 2020-09-01 09:07:46 is 1278459.938277436 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.0: time stamp 2020-09-01 09:08:16 is 1278489.924034925 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/Latest: time stamp 2020-09-01 08:59:46 is 1277979.923929437 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain: time stamp 2020-09-01 08:59:46 is 1277979.923886867 s in the future
tar: var/log/pcp/pmlogger: time stamp 2020-09-01 09:07:29 is 1278442.923866369 s in the future
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
tar: var/log/pcp/pmlogger/pmlogger_check.log.prev: time stamp 2020-09-16 06:44:13 is 1320247.520786053 s in the future
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2020-09-11 01:58:10 is 871084.52059755 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.meta: time stamp 2020-09-16 06:56:12 is 1320966.517370966 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/pmlogger.log: time stamp 2020-09-16 06:56:19 is 1320973.516666835 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.0: time stamp 2020-09-16 06:56:19 is 1320973.498088219 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.index: time stamp 2020-09-16 06:56:19 is 1320973.497122597 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/Latest: time stamp 2020-09-16 06:44:12 is 1320246.497067654 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain: time stamp 2020-09-16 06:44:13 is 1320247.497020435 s in the future
tar: var/log/pcp/pmlogger: time stamp 2020-09-16 07:20:59 is 1322453.496999055 s in the future
tar: var/log/pcp: time stamp 2020-09-16 07:20:50 is 1322444.496983246 s in the future
tar: var/log: time stamp 2020-09-16 07:20:50 is 1322444.496958239 s in the future
tar: var: time stamp 2020-09-16 07:20:50 is 1322444.496937039 s in the future
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
tar: var/log/pcp/pmlogger/m1.cockpit.lan/Latest: time stamp 2021-03-08 10:36:24 is 14649103.995951354 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.meta: time stamp 2021-03-08 10:41:24 is 14649403.99436588 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.0: time stamp 2021-03-08 10:43:24 is 14649523.992733737 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.index: time stamp 2021-03-08 10:41:24 is 14649403.992674146 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/pmlogger.log: time stamp 2021-03-08 10:36:24 is 14649103.992598584 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan: time stamp 2021-03-08 10:36:24 is 14649103.99254853 s in the future
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2021-02-25 07:49:03 is 13688662.992478478 s in the future
tar: var/log/pcp/pmlogger/pmlogger_check.log.prev: time stamp 2021-03-08 10:36:24 is 14649103.992303049 s in the future
tar: var/log/pcp/pmlogger: time stamp 2021-03-08 10:36:54 is 14649133.992260279 s in the future
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
audit: type=1400 audit(1615200500.772:141): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=704 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
audit: type=1400 audit(1615200500.775:142): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=704 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
Wrote JS log to TestHistoryMetrics-testEvents-ubuntu-2404-127.0.0.2-2801-FAIL.js.log
Journal extracted to TestHistoryMetrics-testEvents-ubuntu-2404-127.0.0.2-2801-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/common/testlib.py", line 1913, in tearDown
    self.check_journal_messages()
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2176, in check_journal_messages
    raise Error(UNEXPECTED_MESSAGE + "journal messages:\n" + first)
testlib.Error: FAIL: Test completed, but found unexpected journal messages:
audit: type=1400 audit(1615200500.772:141): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=704 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0

# Result testEvents (__main__.TestHistoryMetrics.testEvents) failed
# 1 TEST FAILED [56s on c81779125fd2]
not ok 30 test/verify/check-metrics TestHistoryMetrics.testEvents

First occurrence: 2024-11-14T18:36:39.801396+00:00 | revision 7aae2ae0c4eec3ba479b2e4f191e109089929559
Times recorded: 1
Latest occurrences:

  • 2024-11-14T18:36:39.801396+00:00 | revision 7aae2ae0c4eec3ba479b2e4f191e109089929559

# ----------------------------------------------------------------------
# testEvents (__main__.TestHistoryMetrics.testEvents)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 46901
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 46901.
Unit [email protected] does not exist, proceeding anyway.
Unit [email protected] does not exist, proceeding anyway.
Created symlink /etc/systemd/system/[email protected] → /dev/null.
Created symlink /etc/systemd/system/[email protected] → /dev/null.
Failed to get triggering units for '[email protected]', ignoring: Invalid argument
Failed to get triggering units for '[email protected]', ignoring: Invalid argument
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2020-08-31 07:42:26 is 1186939.472790311 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/pmlogger.log: time stamp 2020-09-01 08:59:46 is 1277979.471534615 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.index: time stamp 2020-09-01 09:07:46 is 1278459.471377911 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.meta: time stamp 2020-09-01 09:07:46 is 1278459.468577037 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.0: time stamp 2020-09-01 09:08:16 is 1278489.45206993 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/Latest: time stamp 2020-09-01 08:59:46 is 1277979.451988517 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain: time stamp 2020-09-01 08:59:46 is 1277979.451565283 s in the future
tar: var/log/pcp/pmlogger: time stamp 2020-09-01 09:07:29 is 1278442.45147306 s in the future
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
tar: var/log/pcp/pmlogger/pmlogger_check.log.prev: time stamp 2020-09-16 06:44:13 is 1320247.747371196 s in the future
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2020-09-11 01:58:10 is 871084.746957459 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.meta: time stamp 2020-09-16 06:56:12 is 1320966.744207099 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/pmlogger.log: time stamp 2020-09-16 06:56:19 is 1320973.743672126 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.0: time stamp 2020-09-16 06:56:19 is 1320973.723921721 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.index: time stamp 2020-09-16 06:56:19 is 1320973.723236686 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/Latest: time stamp 2020-09-16 06:44:12 is 1320246.723187263 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain: time stamp 2020-09-16 06:44:13 is 1320247.723144082 s in the future
tar: var/log/pcp/pmlogger: time stamp 2020-09-16 07:20:59 is 1322453.723118704 s in the future
tar: var/log/pcp: time stamp 2020-09-16 07:20:50 is 1322444.723102133 s in the future
tar: var/log: time stamp 2020-09-16 07:20:50 is 1322444.72307903 s in the future
tar: var: time stamp 2020-09-16 07:20:50 is 1322444.723058151 s in the future
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
tar: var/log/pcp/pmlogger/m1.cockpit.lan/Latest: time stamp 2021-03-08 10:36:24 is 14649103.965000512 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.meta: time stamp 2021-03-08 10:41:24 is 14649403.963559168 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.0: time stamp 2021-03-08 10:43:24 is 14649523.961708476 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.index: time stamp 2021-03-08 10:41:24 is 14649403.961647131 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/pmlogger.log: time stamp 2021-03-08 10:36:24 is 14649103.9611354 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan: time stamp 2021-03-08 10:36:24 is 14649103.961006128 s in the future
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2021-02-25 07:49:03 is 13688662.960885702 s in the future
tar: var/log/pcp/pmlogger/pmlogger_check.log.prev: time stamp 2021-03-08 10:36:24 is 14649103.960807145 s in the future
tar: var/log/pcp/pmlogger: time stamp 2021-03-08 10:36:54 is 14649133.960776186 s in the future
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
audit: type=1400 audit(1615200500.727:141): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=719 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
audit: type=1400 audit(1615200500.730:142): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=719 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
Wrote JS log to TestHistoryMetrics-testEvents-ubuntu-2404-127.0.0.2-2501-FAIL.js.log
Journal extracted to TestHistoryMetrics-testEvents-ubuntu-2404-127.0.0.2-2501-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/common/testlib.py", line 1913, in tearDown
    self.check_journal_messages()
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2176, in check_journal_messages
    raise Error(UNEXPECTED_MESSAGE + "journal messages:\n" + first)
testlib.Error: FAIL: Test completed, but found unexpected journal messages:
audit: type=1400 audit(1615200500.727:141): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=719 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0

# Result testEvents (__main__.TestHistoryMetrics.testEvents) failed
# 1 TEST FAILED [56s on 474252f9ff98]
not ok 32 test/verify/check-metrics TestHistoryMetrics.testEvents

First occurrence: 2024-11-14T19:05:45.465284+00:00 | revision 2f57d739f04ab5ab9f906e45527adb0a8a2b2cd2
Times recorded: 1
Latest occurrences:

  • 2024-11-14T19:05:45.465284+00:00 | revision 2f57d739f04ab5ab9f906e45527adb0a8a2b2cd2

# ----------------------------------------------------------------------
# testEvents (__main__.TestHistoryMetrics.testEvents)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 50949
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 50949.
Unit [email protected] does not exist, proceeding anyway.
Unit [email protected] does not exist, proceeding anyway.
Created symlink /etc/systemd/system/[email protected] → /dev/null.
Created symlink /etc/systemd/system/[email protected] → /dev/null.
Failed to get triggering units for '[email protected]', ignoring: Invalid argument
Failed to get triggering units for '[email protected]', ignoring: Invalid argument
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2020-08-31 07:42:26 is 1186938.431643327 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/pmlogger.log: time stamp 2020-09-01 08:59:46 is 1277978.430806347 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.index: time stamp 2020-09-01 09:07:46 is 1278458.430750462 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.meta: time stamp 2020-09-01 09:07:46 is 1278458.428239231 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.0: time stamp 2020-09-01 09:08:16 is 1278488.414340134 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/Latest: time stamp 2020-09-01 08:59:46 is 1277978.413736812 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain: time stamp 2020-09-01 08:59:46 is 1277978.413479509 s in the future
tar: var/log/pcp/pmlogger: time stamp 2020-09-01 09:07:29 is 1278441.413456556 s in the future
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
tar: var/log/pcp/pmlogger/pmlogger_check.log.prev: time stamp 2020-09-16 06:44:13 is 1320248.054829921 s in the future
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2020-09-11 01:58:10 is 871085.054303884 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.meta: time stamp 2020-09-16 06:56:12 is 1320967.051580338 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/pmlogger.log: time stamp 2020-09-16 06:56:19 is 1320974.050732598 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.0: time stamp 2020-09-16 06:56:19 is 1320974.030638957 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.index: time stamp 2020-09-16 06:56:19 is 1320974.030501299 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/Latest: time stamp 2020-09-16 06:44:12 is 1320247.030127438 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain: time stamp 2020-09-16 06:44:13 is 1320248.029741794 s in the future
tar: var/log/pcp/pmlogger: time stamp 2020-09-16 07:20:59 is 1322454.029650553 s in the future
tar: var/log/pcp: time stamp 2020-09-16 07:20:50 is 1322445.029584629 s in the future
tar: var/log: time stamp 2020-09-16 07:20:50 is 1322445.029559993 s in the future
tar: var: time stamp 2020-09-16 07:20:50 is 1322445.029539364 s in the future
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
tar: var/log/pcp/pmlogger/m1.cockpit.lan/Latest: time stamp 2021-03-08 10:36:24 is 14649104.373259145 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.meta: time stamp 2021-03-08 10:41:24 is 14649404.371567882 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.0: time stamp 2021-03-08 10:43:24 is 14649524.369495464 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.index: time stamp 2021-03-08 10:41:24 is 14649404.369155987 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/pmlogger.log: time stamp 2021-03-08 10:36:24 is 14649104.368701564 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan: time stamp 2021-03-08 10:36:24 is 14649104.368639047 s in the future
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2021-02-25 07:49:03 is 13688663.368572432 s in the future
tar: var/log/pcp/pmlogger/pmlogger_check.log.prev: time stamp 2021-03-08 10:36:24 is 14649104.368498664 s in the future
tar: var/log/pcp/pmlogger: time stamp 2021-03-08 10:36:54 is 14649134.368463898 s in the future
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
audit: type=1400 audit(1615200500.671:141): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=729 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
audit: type=1400 audit(1615200500.674:142): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=729 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
Wrote JS log to TestHistoryMetrics-testEvents-ubuntu-2404-127.0.0.2-2701-FAIL.js.log
Journal extracted to TestHistoryMetrics-testEvents-ubuntu-2404-127.0.0.2-2701-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/common/testlib.py", line 1913, in tearDown
    self.check_journal_messages()
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2176, in check_journal_messages
    raise Error(UNEXPECTED_MESSAGE + "journal messages:\n" + first)
testlib.Error: FAIL: Test completed, but found unexpected journal messages:
audit: type=1400 audit(1615200500.671:141): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=729 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0

# Result testEvents (__main__.TestHistoryMetrics.testEvents) failed
# 1 TEST FAILED [55s on d87a2af5a943]
not ok 30 test/verify/check-metrics TestHistoryMetrics.testEvents

First occurrence: 2024-11-15T06:46:17.230038+00:00 | revision f86615aaa566994a2aa506b0453247d38aff35a4
Times recorded: 1
Latest occurrences:

  • 2024-11-15T06:46:17.230038+00:00 | revision f86615aaa566994a2aa506b0453247d38aff35a4

# ----------------------------------------------------------------------
# testEvents (__main__.TestHistoryMetrics.testEvents)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 36899
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 36899.
Unit [email protected] does not exist, proceeding anyway.
Unit [email protected] does not exist, proceeding anyway.
Created symlink /etc/systemd/system/[email protected] → /dev/null.
Created symlink /etc/systemd/system/[email protected] → /dev/null.
Failed to get triggering units for '[email protected]', ignoring: Invalid argument
Failed to get triggering units for '[email protected]', ignoring: Invalid argument
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2020-08-31 07:42:26 is 1186937.477844239 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/pmlogger.log: time stamp 2020-09-01 08:59:46 is 1277977.476810179 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.index: time stamp 2020-09-01 09:07:46 is 1278457.476348052 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.meta: time stamp 2020-09-01 09:07:46 is 1278457.474049108 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.0: time stamp 2020-09-01 09:08:16 is 1278487.459461279 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/Latest: time stamp 2020-09-01 08:59:46 is 1277977.45938181 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain: time stamp 2020-09-01 08:59:46 is 1277977.45902996 s in the future
tar: var/log/pcp/pmlogger: time stamp 2020-09-01 09:07:29 is 1278440.458880099 s in the future
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
tar: var/log/pcp/pmlogger/pmlogger_check.log.prev: time stamp 2020-09-16 06:44:13 is 1320247.602956907 s in the future
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2020-09-11 01:58:10 is 871084.602260951 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.meta: time stamp 2020-09-16 06:56:12 is 1320966.599470305 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/pmlogger.log: time stamp 2020-09-16 06:56:19 is 1320973.598447646 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.0: time stamp 2020-09-16 06:56:19 is 1320973.577595224 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.index: time stamp 2020-09-16 06:56:19 is 1320973.577441576 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/Latest: time stamp 2020-09-16 06:44:12 is 1320246.577361676 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain: time stamp 2020-09-16 06:44:13 is 1320247.57692136 s in the future
tar: var/log/pcp/pmlogger: time stamp 2020-09-16 07:20:59 is 1322453.576810451 s in the future
tar: var/log/pcp: time stamp 2020-09-16 07:20:50 is 1322444.576731283 s in the future
tar: var/log: time stamp 2020-09-16 07:20:50 is 1322444.576426922 s in the future
tar: var: time stamp 2020-09-16 07:20:50 is 1322444.576155143 s in the future
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
tar: var/log/pcp/pmlogger/m1.cockpit.lan/Latest: time stamp 2021-03-08 10:36:24 is 14649104.352183802 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.meta: time stamp 2021-03-08 10:41:24 is 14649404.350331287 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.0: time stamp 2021-03-08 10:43:24 is 14649524.348237418 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.index: time stamp 2021-03-08 10:41:24 is 14649404.348093628 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/pmlogger.log: time stamp 2021-03-08 10:36:24 is 14649104.347808373 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan: time stamp 2021-03-08 10:36:24 is 14649104.347349251 s in the future
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2021-02-25 07:49:03 is 13688663.347280753 s in the future
tar: var/log/pcp/pmlogger/pmlogger_check.log.prev: time stamp 2021-03-08 10:36:24 is 14649104.347205932 s in the future
tar: var/log/pcp/pmlogger: time stamp 2021-03-08 10:36:54 is 14649134.347171077 s in the future
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
audit: type=1400 audit(1615200500.706:141): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=733 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
audit: type=1400 audit(1615200500.707:142): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=733 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
Wrote JS log to TestHistoryMetrics-testEvents-ubuntu-2404-127.0.0.2-2201-FAIL.js.log
Journal extracted to TestHistoryMetrics-testEvents-ubuntu-2404-127.0.0.2-2201-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/common/testlib.py", line 1913, in tearDown
    self.check_journal_messages()
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2176, in check_journal_messages
    raise Error(UNEXPECTED_MESSAGE + "journal messages:\n" + first)
testlib.Error: FAIL: Test completed, but found unexpected journal messages:
audit: type=1400 audit(1615200500.706:141): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=733 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0

# Result testEvents (__main__.TestHistoryMetrics.testEvents) failed
# 1 TEST FAILED [57s on d732f9a24bbf]
not ok 30 test/verify/check-metrics TestHistoryMetrics.testEvents

First occurrence: 2024-11-15T08:21:52.948790+00:00 | revision 49c1e4ef8e1d8b9836a879adbe03bed08808bc26
Times recorded: 1
Latest occurrences:

  • 2024-11-15T08:21:52.948790+00:00 | revision 49c1e4ef8e1d8b9836a879adbe03bed08808bc26

# ----------------------------------------------------------------------
# testEvents (__main__.TestHistoryMetrics.testEvents)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 43567
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 43567.
Unit [email protected] does not exist, proceeding anyway.
Unit [email protected] does not exist, proceeding anyway.
Created symlink /etc/systemd/system/[email protected] → /dev/null.
Created symlink /etc/systemd/system/[email protected] → /dev/null.
Failed to get triggering units for '[email protected]', ignoring: Invalid argument
Failed to get triggering units for '[email protected]', ignoring: Invalid argument
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2020-08-31 07:42:26 is 1186939.702205615 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/pmlogger.log: time stamp 2020-09-01 08:59:46 is 1277979.701164804 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.index: time stamp 2020-09-01 09:07:46 is 1278459.700601969 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.meta: time stamp 2020-09-01 09:07:46 is 1278459.698126428 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.0: time stamp 2020-09-01 09:08:16 is 1278489.683718088 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/Latest: time stamp 2020-09-01 08:59:46 is 1277979.683284064 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain: time stamp 2020-09-01 08:59:46 is 1277979.682834392 s in the future
tar: var/log/pcp/pmlogger: time stamp 2020-09-01 09:07:29 is 1278442.682658101 s in the future
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
tar: var/log/pcp/pmlogger/pmlogger_check.log.prev: time stamp 2020-09-16 06:44:13 is 1320247.945196381 s in the future
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2020-09-11 01:58:10 is 871084.944231272 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.meta: time stamp 2020-09-16 06:56:12 is 1320966.941926652 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/pmlogger.log: time stamp 2020-09-16 06:56:19 is 1320973.9407201 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.0: time stamp 2020-09-16 06:56:19 is 1320973.922456282 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.index: time stamp 2020-09-16 06:56:19 is 1320973.922354231 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/Latest: time stamp 2020-09-16 06:44:12 is 1320246.922306632 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain: time stamp 2020-09-16 06:44:13 is 1320247.921779474 s in the future
tar: var/log/pcp/pmlogger: time stamp 2020-09-16 07:20:59 is 1322453.921522913 s in the future
tar: var/log/pcp: time stamp 2020-09-16 07:20:50 is 1322444.921172926 s in the future
tar: var/log: time stamp 2020-09-16 07:20:50 is 1322444.921145695 s in the future
tar: var: time stamp 2020-09-16 07:20:50 is 1322444.921124565 s in the future
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
tar: var/log/pcp/pmlogger/m1.cockpit.lan/Latest: time stamp 2021-03-08 10:36:24 is 14649104.748705291 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.meta: time stamp 2021-03-08 10:41:24 is 14649404.746956342 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.0: time stamp 2021-03-08 10:43:24 is 14649524.744913893 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.index: time stamp 2021-03-08 10:41:24 is 14649404.744491391 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/pmlogger.log: time stamp 2021-03-08 10:36:24 is 14649104.743987918 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan: time stamp 2021-03-08 10:36:24 is 14649104.743932574 s in the future
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2021-02-25 07:49:03 is 13688663.743871509 s in the future
tar: var/log/pcp/pmlogger/pmlogger_check.log.prev: time stamp 2021-03-08 10:36:24 is 14649104.74379737 s in the future
tar: var/log/pcp/pmlogger: time stamp 2021-03-08 10:36:54 is 14649134.743767855 s in the future
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
audit: type=1400 audit(1615200500.656:141): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=722 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
audit: type=1400 audit(1615200500.657:142): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=722 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
Wrote JS log to TestHistoryMetrics-testEvents-ubuntu-2404-127.0.0.2-2501-FAIL.js.log
Journal extracted to TestHistoryMetrics-testEvents-ubuntu-2404-127.0.0.2-2501-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/common/testlib.py", line 1913, in tearDown
    self.check_journal_messages()
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2176, in check_journal_messages
    raise Error(UNEXPECTED_MESSAGE + "journal messages:\n" + first)
testlib.Error: FAIL: Test completed, but found unexpected journal messages:
audit: type=1400 audit(1615200500.656:141): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=722 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0

# Result testEvents (__main__.TestHistoryMetrics.testEvents) failed
# 1 TEST FAILED [53s on 3a5d75acee04]
not ok 32 test/verify/check-metrics TestHistoryMetrics.testEvents

First occurrence: 2024-11-15T08:51:06.304357+00:00 | revision 5fa6bed55763cd668c530bd9fef4f8b759202bb1
Times recorded: 1
Latest occurrences:

  • 2024-11-15T08:51:06.304357+00:00 | revision 5fa6bed55763cd668c530bd9fef4f8b759202bb1

# ----------------------------------------------------------------------
# testEvents (__main__.TestHistoryMetrics.testEvents)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 37621
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 37621.
Unit [email protected] does not exist, proceeding anyway.
Unit [email protected] does not exist, proceeding anyway.
Created symlink /etc/systemd/system/[email protected] → /dev/null.
Created symlink /etc/systemd/system/[email protected] → /dev/null.
Failed to get triggering units for '[email protected]', ignoring: Invalid argument
Failed to get triggering units for '[email protected]', ignoring: Invalid argument
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2020-08-31 07:42:26 is 1186938.239548623 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/pmlogger.log: time stamp 2020-09-01 08:59:46 is 1277978.238399236 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.index: time stamp 2020-09-01 09:07:46 is 1278458.23793245 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.meta: time stamp 2020-09-01 09:07:46 is 1278458.234862051 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.0: time stamp 2020-09-01 09:08:16 is 1278488.219060294 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/Latest: time stamp 2020-09-01 08:59:46 is 1277978.218791951 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain: time stamp 2020-09-01 08:59:46 is 1277978.218308794 s in the future
tar: var/log/pcp/pmlogger: time stamp 2020-09-01 09:07:29 is 1278441.218243131 s in the future
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
tar: var/log/pcp/pmlogger/pmlogger_check.log.prev: time stamp 2020-09-16 06:44:13 is 1320247.845749429 s in the future
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2020-09-11 01:58:10 is 871084.845289066 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.meta: time stamp 2020-09-16 06:56:12 is 1320966.842580294 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/pmlogger.log: time stamp 2020-09-16 06:56:19 is 1320973.842486347 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.0: time stamp 2020-09-16 06:56:19 is 1320973.82440272 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.index: time stamp 2020-09-16 06:56:19 is 1320973.824125359 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/Latest: time stamp 2020-09-16 06:44:12 is 1320246.82407225 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain: time stamp 2020-09-16 06:44:13 is 1320247.823942626 s in the future
tar: var/log/pcp/pmlogger: time stamp 2020-09-16 07:20:59 is 1322453.823919733 s in the future
tar: var/log/pcp: time stamp 2020-09-16 07:20:50 is 1322444.823903713 s in the future
tar: var/log: time stamp 2020-09-16 07:20:50 is 1322444.82383281 s in the future
tar: var: time stamp 2020-09-16 07:20:50 is 1322444.823809637 s in the future
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
tar: var/log/pcp/pmlogger/m1.cockpit.lan/Latest: time stamp 2021-03-08 10:36:24 is 14649103.859780245 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.meta: time stamp 2021-03-08 10:41:24 is 14649403.857961313 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.0: time stamp 2021-03-08 10:43:24 is 14649523.855596446 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.index: time stamp 2021-03-08 10:41:24 is 14649403.855120864 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/pmlogger.log: time stamp 2021-03-08 10:36:24 is 14649103.854626286 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan: time stamp 2021-03-08 10:36:24 is 14649103.854570812 s in the future
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2021-02-25 07:49:03 is 13688662.854512352 s in the future
tar: var/log/pcp/pmlogger/pmlogger_check.log.prev: time stamp 2021-03-08 10:36:24 is 14649103.854158879 s in the future
tar: var/log/pcp/pmlogger: time stamp 2021-03-08 10:36:54 is 14649133.853921243 s in the future
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
audit: type=1400 audit(1615200500.679:141): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=742 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
audit: type=1400 audit(1615200500.681:142): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=742 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
Wrote JS log to TestHistoryMetrics-testEvents-ubuntu-2404-127.0.0.2-2501-FAIL.js.log
Journal extracted to TestHistoryMetrics-testEvents-ubuntu-2404-127.0.0.2-2501-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/common/testlib.py", line 1913, in tearDown
    self.check_journal_messages()
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2176, in check_journal_messages
    raise Error(UNEXPECTED_MESSAGE + "journal messages:\n" + first)
testlib.Error: FAIL: Test completed, but found unexpected journal messages:
audit: type=1400 audit(1615200500.679:141): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=742 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0

# Result testEvents (__main__.TestHistoryMetrics.testEvents) failed
# 1 TEST FAILED [57s on c4a90430605e]
not ok 30 test/verify/check-metrics TestHistoryMetrics.testEvents

First occurrence: 2024-11-15T09:27:53.200468+00:00 | revision f1a392550402bbea730f4c7ad86b9559cd84a43a
Times recorded: 1
Latest occurrences:

  • 2024-11-15T09:27:53.200468+00:00 | revision f1a392550402bbea730f4c7ad86b9559cd84a43a

# ----------------------------------------------------------------------
# testEvents (__main__.TestHistoryMetrics.testEvents)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 34165
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 34165.
Unit [email protected] does not exist, proceeding anyway.
Unit [email protected] does not exist, proceeding anyway.
Created symlink /etc/systemd/system/[email protected] → /dev/null.
Created symlink /etc/systemd/system/[email protected] → /dev/null.
Failed to get triggering units for '[email protected]', ignoring: Invalid argument
Failed to get triggering units for '[email protected]', ignoring: Invalid argument
> warn: CPU temperature metric closed: Object(3)
> warn: Failed to get current crypto policy: not-found ; falling back to /etc/crypto-policies/config
> error: TypeError: Cannot read properties of null (reading 'trim')
> info: failed to fetch lastlog2: Object(5)
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2020-08-31 07:42:26 is 1186937.637947696 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/pmlogger.log: time stamp 2020-09-01 08:59:46 is 1277977.636949012 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.index: time stamp 2020-09-01 09:07:46 is 1278457.636775707 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.meta: time stamp 2020-09-01 09:07:46 is 1278457.634644489 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.0: time stamp 2020-09-01 09:08:16 is 1278487.619308861 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/Latest: time stamp 2020-09-01 08:59:46 is 1277977.619228019 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain: time stamp 2020-09-01 08:59:46 is 1277977.618837196 s in the future
tar: var/log/pcp/pmlogger: time stamp 2020-09-01 09:07:29 is 1278440.618659442 s in the future
> warn: CPU temperature metric closed: Object(3)
> warn: Failed to get current crypto policy: not-found ; falling back to /etc/crypto-policies/config
> error: TypeError: Cannot read properties of null (reading 'trim')
> info: failed to fetch lastlog2: Object(5)
tar: var/log/pcp/pmlogger/pmlogger_check.log.prev: time stamp 2020-09-16 06:44:13 is 1320247.73271381 s in the future
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2020-09-11 01:58:10 is 871084.732179588 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.meta: time stamp 2020-09-16 06:56:12 is 1320966.729921421 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/pmlogger.log: time stamp 2020-09-16 06:56:19 is 1320973.729081957 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.0: time stamp 2020-09-16 06:56:19 is 1320973.711381788 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.index: time stamp 2020-09-16 06:56:19 is 1320973.711202251 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/Latest: time stamp 2020-09-16 06:44:12 is 1320246.711151216 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain: time stamp 2020-09-16 06:44:13 is 1320247.710656097 s in the future
tar: var/log/pcp/pmlogger: time stamp 2020-09-16 07:20:59 is 1322453.710489224 s in the future
tar: var/log/pcp: time stamp 2020-09-16 07:20:50 is 1322444.710469787 s in the future
tar: var/log: time stamp 2020-09-16 07:20:50 is 1322444.710445862 s in the future
tar: var: time stamp 2020-09-16 07:20:50 is 1322444.710425103 s in the future
> warn: CPU temperature metric closed: Object(3)
> warn: Failed to get current crypto policy: not-found ; falling back to /etc/crypto-policies/config
> error: TypeError: Cannot read properties of null (reading 'trim')
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
> warn: Failed to get current crypto policy: not-found ; falling back to /etc/crypto-policies/config
> error: TypeError: Cannot read properties of null (reading 'trim')
> info: failed to fetch lastlog2: Object(5)
> warn: Failed to get current crypto policy: not-found ; falling back to /etc/crypto-policies/config
> error: TypeError: Cannot read properties of null (reading 'trim')
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
tar: var/log/pcp/pmlogger/m1.cockpit.lan/Latest: time stamp 2021-03-08 10:36:24 is 14649104.225828912 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.meta: time stamp 2021-03-08 10:41:24 is 14649404.223689959 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.0: time stamp 2021-03-08 10:43:24 is 14649524.221886365 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.index: time stamp 2021-03-08 10:41:24 is 14649404.221591431 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/pmlogger.log: time stamp 2021-03-08 10:36:24 is 14649104.221033815 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan: time stamp 2021-03-08 10:36:24 is 14649104.220978842 s in the future
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2021-02-25 07:49:03 is 13688663.220919411 s in the future
tar: var/log/pcp/pmlogger/pmlogger_check.log.prev: time stamp 2021-03-08 10:36:24 is 14649104.220847155 s in the future
tar: var/log/pcp/pmlogger: time stamp 2021-03-08 10:36:54 is 14649134.220814133 s in the future
> warn: CPU temperature metric closed: Object(3)
> warn: Failed to get current crypto policy: not-found ; falling back to /etc/crypto-policies/config
> error: TypeError: Cannot read properties of null (reading 'trim')
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
> warn: Failed to get current crypto policy: not-found ; falling back to /etc/crypto-policies/config
> error: TypeError: Cannot read properties of null (reading 'trim')
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
audit: type=1400 audit(1615200500.673:141): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=714 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
audit: type=1400 audit(1615200500.673:142): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=714 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
Wrote JS log to TestHistoryMetrics-testEvents-ubuntu-2404-127.0.0.2-2701-FAIL.js.log
Journal extracted to TestHistoryMetrics-testEvents-ubuntu-2404-127.0.0.2-2701-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/common/testlib.py", line 1913, in tearDown
    self.check_journal_messages()
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2176, in check_journal_messages
    raise Error(UNEXPECTED_MESSAGE + "journal messages:\n" + first)
testlib.Error: FAIL: Test completed, but found unexpected journal messages:
audit: type=1400 audit(1615200500.673:141): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=714 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0

# Result testEvents (__main__.TestHistoryMetrics.testEvents) failed
# 1 TEST FAILED [56s on 15c9567a35a6]
not ok 32 test/verify/check-metrics TestHistoryMetrics.testEvents

First occurrence: 2024-11-15T10:11:43.375225+00:00 | revision dae0b2f7c90dea2d1eefcc1c00b651ab882b7826
Times recorded: 1
Latest occurrences:

  • 2024-11-15T10:11:43.375225+00:00 | revision dae0b2f7c90dea2d1eefcc1c00b651ab882b7826

# ----------------------------------------------------------------------
# testEvents (__main__.TestHistoryMetrics.testEvents)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 34913
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 34913.
Unit [email protected] does not exist, proceeding anyway.
Unit [email protected] does not exist, proceeding anyway.
Created symlink /etc/systemd/system/[email protected] → /dev/null.
Created symlink /etc/systemd/system/[email protected] → /dev/null.
Failed to get triggering units for '[email protected]', ignoring: Invalid argument
Failed to get triggering units for '[email protected]', ignoring: Invalid argument
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2020-08-31 07:42:26 is 1186939.231943247 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/pmlogger.log: time stamp 2020-09-01 08:59:46 is 1277979.230900732 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.index: time stamp 2020-09-01 09:07:46 is 1278459.230693664 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.meta: time stamp 2020-09-01 09:07:46 is 1278459.228160523 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.0: time stamp 2020-09-01 09:08:16 is 1278489.213161925 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/Latest: time stamp 2020-09-01 08:59:46 is 1277979.212809263 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain: time stamp 2020-09-01 08:59:46 is 1277979.212354981 s in the future
tar: var/log/pcp/pmlogger: time stamp 2020-09-01 09:07:29 is 1278442.21203448 s in the future
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
tar: var/log/pcp/pmlogger/pmlogger_check.log.prev: time stamp 2020-09-16 06:44:13 is 1320247.985914332 s in the future
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2020-09-11 01:58:10 is 871084.985193851 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.meta: time stamp 2020-09-16 06:56:12 is 1320966.982573046 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/pmlogger.log: time stamp 2020-09-16 06:56:19 is 1320973.981907137 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.0: time stamp 2020-09-16 06:56:19 is 1320973.96338455 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.index: time stamp 2020-09-16 06:56:19 is 1320973.963126236 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/Latest: time stamp 2020-09-16 06:44:12 is 1320246.96282973 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain: time stamp 2020-09-16 06:44:13 is 1320247.962726306 s in the future
tar: var/log/pcp/pmlogger: time stamp 2020-09-16 07:20:59 is 1322453.962679438 s in the future
tar: var/log/pcp: time stamp 2020-09-16 07:20:50 is 1322444.962662737 s in the future
tar: var/log: time stamp 2020-09-16 07:20:50 is 1322444.962641878 s in the future
tar: var: time stamp 2020-09-16 07:20:50 is 1322444.962620738 s in the future
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
tar: var/log/pcp/pmlogger/m1.cockpit.lan/Latest: time stamp 2021-03-08 10:36:24 is 14649104.03235299 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.meta: time stamp 2021-03-08 10:41:24 is 14649404.030679933 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.0: time stamp 2021-03-08 10:43:24 is 14649524.028521285 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.index: time stamp 2021-03-08 10:41:24 is 14649404.028456223 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/pmlogger.log: time stamp 2021-03-08 10:36:24 is 14649104.028075699 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan: time stamp 2021-03-08 10:36:24 is 14649104.027787438 s in the future
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2021-02-25 07:49:03 is 13688663.027689254 s in the future
tar: var/log/pcp/pmlogger/pmlogger_check.log.prev: time stamp 2021-03-08 10:36:24 is 14649104.027606609 s in the future
tar: var/log/pcp/pmlogger: time stamp 2021-03-08 10:36:54 is 14649134.027573637 s in the future
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
audit: type=1400 audit(1615200500.700:141): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=724 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
audit: type=1400 audit(1615200500.702:142): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=724 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
Wrote JS log to TestHistoryMetrics-testEvents-ubuntu-2404-127.0.0.2-2201-FAIL.js.log
Journal extracted to TestHistoryMetrics-testEvents-ubuntu-2404-127.0.0.2-2201-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/common/testlib.py", line 1927, in tearDown
    self.check_journal_messages()
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2190, in check_journal_messages
    raise Error(UNEXPECTED_MESSAGE + "journal messages:\n" + first)
testlib.Error: FAIL: Test completed, but found unexpected journal messages:
audit: type=1400 audit(1615200500.700:141): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=724 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0

# Result testEvents (__main__.TestHistoryMetrics.testEvents) failed
# 1 TEST FAILED [61s on 9abb90fedd81]
not ok 32 test/verify/check-metrics TestHistoryMetrics.testEvents

First occurrence: 2024-11-15T10:44:27.316902+00:00 | revision 917f36465f4aac00d133e08abe57d3182c076c3c
Times recorded: 1
Latest occurrences:

  • 2024-11-15T10:44:27.316902+00:00 | revision 917f36465f4aac00d133e08abe57d3182c076c3c

# ----------------------------------------------------------------------
# testEvents (__main__.TestHistoryMetrics.testEvents)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 51329
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 51329.
Unit [email protected] does not exist, proceeding anyway.
Unit [email protected] does not exist, proceeding anyway.
Created symlink /etc/systemd/system/[email protected] → /dev/null.
Created symlink /etc/systemd/system/[email protected] → /dev/null.
Failed to get triggering units for '[email protected]', ignoring: Invalid argument
Failed to get triggering units for '[email protected]', ignoring: Invalid argument
> warn: CPU temperature metric closed: Object(3)
> warn: Failed to get current crypto policy: not-found ; falling back to /etc/crypto-policies/config
> error: TypeError: Cannot read properties of null (reading 'trim')
> info: failed to fetch lastlog2: Object(5)
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2020-08-31 07:42:26 is 1186938.109611241 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/pmlogger.log: time stamp 2020-09-01 08:59:46 is 1277978.108221244 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.index: time stamp 2020-09-01 09:07:46 is 1278458.108074449 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.meta: time stamp 2020-09-01 09:07:46 is 1278458.103255271 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200901.04.59.0: time stamp 2020-09-01 09:08:16 is 1278488.073279675 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/Latest: time stamp 2020-09-01 08:59:46 is 1277978.073148109 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain: time stamp 2020-09-01 08:59:46 is 1277978.072654272 s in the future
tar: var/log/pcp/pmlogger: time stamp 2020-09-01 09:07:29 is 1278441.072559645 s in the future
> warn: CPU temperature metric closed: Object(3)
> warn: Failed to get current crypto policy: not-found ; falling back to /etc/crypto-policies/config
> error: TypeError: Cannot read properties of null (reading 'trim')
> info: failed to fetch lastlog2: Object(5)
tar: var/log/pcp/pmlogger/pmlogger_check.log.prev: time stamp 2020-09-16 06:44:13 is 1320247.594616507 s in the future
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2020-09-11 01:58:10 is 871084.593693115 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.meta: time stamp 2020-09-16 06:56:12 is 1320966.591022688 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/pmlogger.log: time stamp 2020-09-16 06:56:19 is 1320973.590603021 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.0: time stamp 2020-09-16 06:56:19 is 1320973.571232332 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/20200916.02.44.index: time stamp 2020-09-16 06:56:19 is 1320973.571113359 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain/Latest: time stamp 2020-09-16 06:44:12 is 1320246.571063966 s in the future
tar: var/log/pcp/pmlogger/localhost.localdomain: time stamp 2020-09-16 06:44:13 is 1320247.571020465 s in the future
tar: var/log/pcp/pmlogger: time stamp 2020-09-16 07:20:59 is 1322453.570999385 s in the future
tar: var/log/pcp: time stamp 2020-09-16 07:20:50 is 1322444.570983425 s in the future
tar: var/log: time stamp 2020-09-16 07:20:50 is 1322444.570960903 s in the future
tar: var: time stamp 2020-09-16 07:20:50 is 1322444.570939563 s in the future
> warn: CPU temperature metric closed: Object(3)
> warn: Failed to get current crypto policy: not-found ; falling back to /etc/crypto-policies/config
> error: TypeError: Cannot read properties of null (reading 'trim')
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
> warn: Failed to get current crypto policy: not-found ; falling back to /etc/crypto-policies/config
> error: TypeError: Cannot read properties of null (reading 'trim')
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
> warn: Failed to get current crypto policy: not-found ; falling back to /etc/crypto-policies/config
> error: TypeError: Cannot read properties of null (reading 'trim')
tar: var/log/pcp/pmlogger/m1.cockpit.lan/Latest: time stamp 2021-03-08 10:36:24 is 14649103.57011396 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.meta: time stamp 2021-03-08 10:41:24 is 14649403.568411678 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.0: time stamp 2021-03-08 10:43:24 is 14649523.566554775 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/20210308.10.36.index: time stamp 2021-03-08 10:41:24 is 14649403.566489934 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan/pmlogger.log: time stamp 2021-03-08 10:36:24 is 14649103.566010785 s in the future
tar: var/log/pcp/pmlogger/m1.cockpit.lan: time stamp 2021-03-08 10:36:24 is 14649103.565930434 s in the future
tar: var/log/pcp/pmlogger/.NeedRewrite: time stamp 2021-02-25 07:49:03 is 13688662.565804487 s in the future
tar: var/log/pcp/pmlogger/pmlogger_check.log.prev: time stamp 2021-03-08 10:36:24 is 14649103.565722914 s in the future
tar: var/log/pcp/pmlogger: time stamp 2021-03-08 10:36:54 is 14649133.565690824 s in the future
> warn: CPU temperature metric closed: Object(3)
> warn: Failed to get current crypto policy: not-found ; falling back to /etc/crypto-policies/config
> error: TypeError: Cannot read properties of null (reading 'trim')
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
> warn: Failed to get current crypto policy: not-found ; falling back to /etc/crypto-policies/config
> error: TypeError: Cannot read properties of null (reading 'trim')
> info: failed to fetch lastlog2: Object(5)
> warn: CPU temperature metric closed: Object(3)
audit: type=1400 audit(1615200500.682:141): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=737 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
audit: type=1400 audit(1615200500.682:142): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=737 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0
Wrote JS log to TestHistoryMetrics-testEvents-ubuntu-2404-127.0.0.2-2501-FAIL.js.log
Journal extracted to TestHistoryMetrics-testEvents-ubuntu-2404-127.0.0.2-2501-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/common/testlib.py", line 1927, in tearDown
    self.check_journal_messages()
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2190, in check_journal_messages
    raise Error(UNEXPECTED_MESSAGE + "journal messages:\n" + first)
testlib.Error: FAIL: Test completed, but found unexpected journal messages:
audit: type=1400 audit(1615200500.682:141): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/proc/sys/net/ipv6/conf/all/disable_ipv6" pid=737 comm="rsyslogd" requested_mask="r" denied_mask="r" fsuid=102 ouid=0

# Result testEvents (__main__.TestHistoryMetrics.testEvents) failed
# 1 TEST FAILED [63s on d065d56a4502]
not ok 30 test/verify/check-metrics TestHistoryMetrics.testEvents

First occurrence: 2024-11-15T12:16:40.062781+00:00 | revision c6c1d4d7630ddc01f55c82ba5be14e8ee0a6dadb
Times recorded: 1
Latest occurrences:

  • 2024-11-15T12:16:40.062781+00:00 | revision c6c1d4d7630ddc01f55c82ba5be14e8ee0a6dadb

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
Development

No branches or pull requests

2 participants