Reproducing the issue *before* the patch:
root@foo:~# dpkg -l| grep -wF ' systemd '
ii systemd 245.4-4ubuntu3.1 amd64 system
and service manager
root@foo:~# systemctl status test.service
● test.service - Test Truncate
Loaded: loaded (/etc/systemd/system/test.service; enabled; vendor preset:
enabled)
Active: active (exited) since Mon 2020-07-20 18:08:56 UTC; 53s ago
Process: 228 ExecStart=/usr/lib/test.sh long-test-for-start (code=exited,
status=0/SUCCESS)
Main PID: 228 (code=exited, status=0/SUCCESS)
Jul 20 18:08:56 foo systemd[1]: Starting Test Truncate...
Jul 20 18:08:56 foo test.sh[229]: This will
Jul 20 18:08:56 foo test.sh[228]: T
Jul 20 18:08:56 foo test.sh[230]: T
Jul 20 18:08:56 foo test.sh[230]: sually fai
Jul 20 18:08:56 foo test.sh[228]: s
Jul 20 18:08:56 foo test.sh[231]: s
Jul 20 18:08:56 foo test.sh[231]: nd be truncate
Jul 20 18:08:56 foo test.sh[228]: n
Jul 20 18:08:56 foo systemd[1]: Finished Test Truncate.
Upgrading to focal-proposed version:
root@foo:~# apt-get dist-upgrade -V
Reading package lists... Done
Building dependency tree
Reading state information... Done
Calculating upgrade... Done
The following packages will be upgraded:
...
libnss-systemd (245.4-4ubuntu3.1 => 245.4-4ubuntu3.2)
libpam-systemd (245.4-4ubuntu3.1 => 245.4-4ubuntu3.2)
libseccomp2 (2.4.3-1ubuntu3.20.04.2 => 2.4.3-1ubuntu3.20.04.3)
libsystemd0 (245.4-4ubuntu3.1 => 245.4-4ubuntu3.2)
libudev1 (245.4-4ubuntu3.1 => 245.4-4ubuntu3.2)
systemd (245.4-4ubuntu3.1 => 245.4-4ubuntu3.2)
systemd-sysv (245.4-4ubuntu3.1 => 245.4-4ubuntu3.2)
systemd-timesyncd (245.4-4ubuntu3.1 => 245.4-4ubuntu3.2)
udev (245.4-4ubuntu3.1 => 245.4-4ubuntu3.2)
...
17 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
root@foo:~# reboot
Verifying the bug is fixed:
root@foo:~# systemctl status test.service
● test.service - Test Truncate
Loaded: loaded (/etc/systemd/system/test.service; enabled; vendor preset:
enabled)
Active: active (exited) since Mon 2020-07-20 18:12:09 UTC; 1s ago
Process: 139 ExecStart=/usr/lib/test.sh long-test-for-start (code=exited,
status=0/SUCCESS)
Main PID: 139 (code=exited, status=0/SUCCESS)
Jul 20 18:12:09 foo systemd[1]: Starting Test Truncate...
Jul 20 18:12:09 foo test.sh[140]: This will
Jul 20 18:12:09 foo test.sh[141]: usually fail
Jul 20 18:12:09 foo test.sh[142]: and be truncated
Jul 20 18:12:09 foo systemd[1]: Finished Test Truncate.
It is so marking as such, thanks!
** Tags removed: verification-needed verification-needed-focal
** Tags added: verification-done verification-done-focal
--
You received this bug notification because you are a member of Ubuntu
Touch seeded packages, which is subscribed to systemd in Ubuntu.
https://bugs.launchpad.net/bugs/1875708
Title:
Truncated messages in journald since systemd v244
Status in libvirt package in Ubuntu:
Invalid
Status in systemd package in Ubuntu:
Fix Released
Status in libvirt source package in Focal:
Confirmed
Status in systemd source package in Focal:
Fix Committed
Status in libvirt source package in Groovy:
Invalid
Status in systemd source package in Groovy:
Fix Released
Bug description:
[Impact]
* since 09d0b46a "journal: refresh cached credentials of stdout streams"
in ~244 output may be trincated.
* Upstream has a fix in https://github.com/systemd/systemd/pull/15685
* Backporting the fix will avoid truncation of log output to journald
[Test Case]
* This could happen in any case, but is more likely when a program that
has output going to journald is spawning short-lived sub-programs often.
Therefore the test emphasizes on that:
- Use a test service like /etc/systemd/system/test.service:
[Unit]
Description=Test Truncate
After=network.target
[Service]
ExecStart=/usr/lib/test.sh long-test-for-start
ExecStop=/usr/lib/test.sh long-test-for-stop
Type=oneshot
RemainAfterExit=yes
StandardOutput=journal+console
TimeoutStopSec=0
[Install]
WantedBy=multi-user.target
- And a test script like /usr/lib/test.sh:
#!/bin/sh
gettext "This will"
echo
gettext "usually fail"
echo
gettext "and be truncated"
echo
Start/Stopping that service without the fix will look like:
Apr 30 18:56:40 f systemd[1]: Stopping Test Truncate...
Apr 30 18:56:40 f test.sh[1165]: T
Apr 30 18:56:40 f test.sh[1167]: T
Apr 30 18:56:40 f test.sh[1167]: sually fai
Apr 30 18:56:40 f test.sh[1165]: s
Apr 30 18:56:40 f test.sh[1168]: s
Apr 30 18:56:40 f test.sh[1168]: nd be truncate
Apr 30 18:56:40 f test.sh[1165]: n
Apr 30 18:56:40 f systemd[1]: test.service: Succeeded.
Apr 30 18:56:40 f systemd[1]: Stopped Test Truncate.
[Regression Potential]
* The patches are rather small, but there might be a slightly increased
memory consumption of journald for output buffers.
* Issues (if any and I couldn't find any so far) should be only to
journald output handling. Systemd is huge, this at least narrows
down the potential places of a regression a lot.
[Other Info]
* n/a
--- --- --- --- --- --- --- --- --- --- --- --- --- --- ---
Originally reported against libvirt which happens to be one of the
example-triggers
Hi,
when I shut down my machine I see messages from /usr/lib/libvirt
/libvirt-guests.sh but there are 2 anomalies:
- 3 libvirt-guests.sh processes are run
- messages are truncated
ProblemType: Bug
DistroRelease: Ubuntu 20.04
Package: libvirt-daemon 6.0.0-0ubuntu8
Uname: Linux 5.6.7-050607-generic x86_64
ApportVersion: 2.20.11-0ubuntu27
Architecture: amd64
CasperMD5CheckResult: skip
CurrentDesktop: KDE
Date: Tue Apr 28 19:42:56 2020
SourcePackage: libvirt
UpgradeStatus: No upgrade log present (probably fresh install)
modified.conffile..etc.libvirt.nwfilter.allow-arp.xml: [inaccessible: [Errno
13] Permission denied: '/etc/libvirt/nwfilter/allow-arp.xml']
modified.conffile..etc.libvirt.nwfilter.allow-dhcp-server.xml: [inaccessible:
[Errno 13] Permission denied: '/etc/libvirt/nwfilter/allow-dhcp-server.xml']
modified.conffile..etc.libvirt.nwfilter.allow-dhcp.xml: [inaccessible: [Errno
13] Permission denied: '/etc/libvirt/nwfilter/allow-dhcp.xml']
modified.conffile..etc.libvirt.nwfilter.allow-incoming-ipv4.xml:
[inaccessible: [Errno 13] Permission denied:
'/etc/libvirt/nwfilter/allow-incoming-ipv4.xml']
modified.conffile..etc.libvirt.nwfilter.allow-ipv4.xml: [inaccessible: [Errno
13] Permission denied: '/etc/libvirt/nwfilter/allow-ipv4.xml']
modified.conffile..etc.libvirt.nwfilter.clean-traffic-gateway.xml:
[inaccessible: [Errno 13] Permission denied:
'/etc/libvirt/nwfilter/clean-traffic-gateway.xml']
modified.conffile..etc.libvirt.nwfilter.clean-traffic.xml: [inaccessible:
[Errno 13] Permission denied: '/etc/libvirt/nwfilter/clean-traffic.xml']
modified.conffile..etc.libvirt.nwfilter.no-arp-ip-spoofing.xml:
[inaccessible: [Errno 13] Permission denied:
'/etc/libvirt/nwfilter/no-arp-ip-spoofing.xml']
modified.conffile..etc.libvirt.nwfilter.no-arp-mac-spoofing.xml:
[inaccessible: [Errno 13] Permission denied:
'/etc/libvirt/nwfilter/no-arp-mac-spoofing.xml']
modified.conffile..etc.libvirt.nwfilter.no-arp-spoofing.xml: [inaccessible:
[Errno 13] Permission denied: '/etc/libvirt/nwfilter/no-arp-spoofing.xml']
modified.conffile..etc.libvirt.nwfilter.no-ip-multicast.xml: [inaccessible:
[Errno 13] Permission denied: '/etc/libvirt/nwfilter/no-ip-multicast.xml']
modified.conffile..etc.libvirt.nwfilter.no-ip-spoofing.xml: [inaccessible:
[Errno 13] Permission denied: '/etc/libvirt/nwfilter/no-ip-spoofing.xml']
modified.conffile..etc.libvirt.nwfilter.no-mac-broadcast.xml: [inaccessible:
[Errno 13] Permission denied: '/etc/libvirt/nwfilter/no-mac-broadcast.xml']
modified.conffile..etc.libvirt.nwfilter.no-mac-spoofing.xml: [inaccessible:
[Errno 13] Permission denied: '/etc/libvirt/nwfilter/no-mac-spoofing.xml']
modified.conffile..etc.libvirt.nwfilter.no-other-l2-traffic.xml:
[inaccessible: [Errno 13] Permission denied:
'/etc/libvirt/nwfilter/no-other-l2-traffic.xml']
modified.conffile..etc.libvirt.nwfilter.no-other-rarp-traffic.xml:
[inaccessible: [Errno 13] Permission denied:
'/etc/libvirt/nwfilter/no-other-rarp-traffic.xml']
modified.conffile..etc.libvirt.nwfilter.qemu-announce-self-rarp.xml:
[inaccessible: [Errno 13] Permission denied:
'/etc/libvirt/nwfilter/qemu-announce-self-rarp.xml']
modified.conffile..etc.libvirt.nwfilter.qemu-announce-self.xml:
[inaccessible: [Errno 13] Permission denied:
'/etc/libvirt/nwfilter/qemu-announce-self.xml']
modified.conffile..etc.libvirt.qemu.conf: [inaccessible: [Errno 13]
Permission denied: '/etc/libvirt/qemu.conf']
modified.conffile..etc.libvirt.qemu.networks.default.xml: [inaccessible:
[Errno 13] Permission denied: '/etc/libvirt/qemu/networks/default.xml']
To manage notifications about this bug go to:
https://bugs.launchpad.net/ubuntu/+source/libvirt/+bug/1875708/+subscriptions
--
Mailing list: https://launchpad.net/~touch-packages
Post to : touch-packages@lists.launchpad.net
Unsubscribe : https://launchpad.net/~touch-packages
More help : https://help.launchpad.net/ListHelp
