Bug 14556 - Running dates tests in systemd images causes loss of networking
Summary: Running dates tests in systemd images causes loss of networking
Status: RESOLVED FIXED
Alias: None
Product: OE-Core
Classification: Build System, Metadata & Runtime
Component: core (show other bugs)
Version: 0.0.0
Hardware: x86 Multiple
: Medium normal
Target Milestone: 4.0
Assignee: Ross Burton
QA Contact:
URL:
Whiteboard: AB-INT
Depends on:
Blocks:
 
Reported: 2021-09-21 15:02 UTC by Richard Purdie
Modified: 2022-04-14 13:40 UTC (History)
4 users (show)

See Also:
OS type for building Yocto: ---
Type of Regression: ---
Verified:
Documentation change: No (bug/feature does not impact docs)


Attachments

Note You need to log in before you can comment on or make changes to this bug.
Description Richard Purdie 2021-09-21 15:02:47 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/101/builds/2891

A qemux86-alt image failed where networking was lost part way through running tests.

Critical section of the logs shows it runs connmand tests, then breaks networking when "systemctl disable --now --runtime systemd-timesyncd" is run:


DEBUG: [Command returned '0' after 0.31 seconds]
DEBUG: Command: ps | grep [c]onnmand
Status: 0 Output:    197 root     13488 S    /usr/sbin/connmand -n

NOTE:  ... ok
NOTE: test_date (date.DateTest)
DEBUG: Checking if at least one of busybox, coreutils is installed
DEBUG: Stopping systemd-timesyncd daemon
DEBUG: [Running]$ ssh -l root -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.6 export PA
TH=/usr/sbin:/sbin:/usr/bin:/bin; systemctl disable --now --runtime systemd-timesyncd
DEBUG: time: 1632226756.1396277, endtime: 1632227056.1332355
DEBUG: time: 1632226761.145248, endtime: 1632227056.1332355
DEBUG: time: 1632226766.1491456, endtime: 1632227056.1332355
DEBUG: time: 1632226771.1545548, endtime: 1632227056.1332355
DEBUG: time: 1632226776.1571183, endtime: 1632227056.1332355
DEBUG: time: 1632226781.162464, endtime: 1632227056.1332355
DEBUG: time: 1632226786.167908, endtime: 1632227056.1332355
DEBUG: time: 1632226791.173214, endtime: 1632227056.1332355
DEBUG: time: 1632226796.178648, endtime: 1632227056.1332355
DEBUG: time: 1632226801.1840577, endtime: 1632227056.1332355
DEBUG: time: 1632226806.189491, endtime: 1632227056.1332355
DEBUG: time: 1632226811.1949248, endtime: 1632227056.1332355
DEBUG: time: 1632226816.200386, endtime: 1632227056.1332355
DEBUG: time: 1632226821.2058387, endtime: 1632227056.1332355
DEBUG: time: 1632226826.2091506, endtime: 1632227056.1332355
DEBUG: time: 1632226831.2146323, endtime: 1632227056.1332355
DEBUG: time: 1632226836.2200458, endtime: 1632227056.1332355
DEBUG: time: 1632226841.225476, endtime: 1632227056.1332355
DEBUG: time: 1632226846.231046, endtime: 1632227056.1332355
DEBUG: time: 1632226851.2331502, endtime: 1632227056.1332355
DEBUG: time: 1632226856.2371256, endtime: 1632227056.1332355
DEBUG: time: 1632226861.2411518, endtime: 1632227056.1332355
DEBUG: time: 1632226866.2466006, endtime: 1632227056.1332355
DEBUG: time: 1632226871.249104, endtime: 1632227056.1332355
DEBUG: time: 1632226876.2531333, endtime: 1632227056.1332355
DEBUG: time: 1632226881.257126, endtime: 1632227056.1332355
DEBUG: time: 1632226886.261188, endtime: 1632227056.1332355
DEBUG: time: 1632226891.2651696, endtime: 1632227056.1332355
DEBUG: time: 1632226896.2691092, endtime: 1632227056.1332355
DEBUG: time: 1632226901.273126, endtime: 1632227056.1332355
DEBUG: time: 1632226906.2771702, endtime: 1632227056.1332355
DEBUG: time: 1632226911.2811468, endtime: 1632227056.1332355

DEBUG: time: 1632226916.28659, endtime: 1632227056.1332355
DEBUG: time: 1632226921.2919717, endtime: 1632227056.1332355
DEBUG: time: 1632226926.2971501, endtime: 1632227056.1332355
DEBUG: time: 1632226931.3026037, endtime: 1632227056.1332355
DEBUG: time: 1632226936.3080604, endtime: 1632227056.1332355
DEBUG: time: 1632226941.3131642, endtime: 1632227056.1332355
DEBUG: time: 1632226946.31863, endtime: 1632227056.1332355
DEBUG: time: 1632226951.324099, endtime: 1632227056.1332355
DEBUG: time: 1632226956.3295658, endtime: 1632227056.1332355
DEBUG: time: 1632226961.3350563, endtime: 1632227056.1332355
DEBUG: time: 1632226966.3405077, endtime: 1632227056.1332355
DEBUG: time: 1632226971.3459697, endtime: 1632227056.1332355
DEBUG: time: 1632226978.2476435, endtime: 1632227056.1332355
DEBUG: time: 1632226983.253075, endtime: 1632227056.1332355
DEBUG: time: 1632226988.2584589, endtime: 1632227056.1332355
DEBUG: time: 1632226993.2639263, endtime: 1632227056.1332355
DEBUG: time: 1632226998.269359, endtime: 1632227056.1332355
DEBUG: time: 1632227003.273125, endtime: 1632227056.1332355
DEBUG: time: 1632227008.2771275, endtime: 1632227056.1332355
DEBUG: time: 1632227013.2825418, endtime: 1632227056.1332355
DEBUG: time: 1632227018.2880216, endtime: 1632227056.1332355
DEBUG: time: 1632227023.2931232, endtime: 1632227056.1332355
DEBUG: time: 1632227028.297147, endtime: 1632227056.1332355
DEBUG: time: 1632227033.301108, endtime: 1632227056.1332355
DEBUG: time: 1632227038.3051462, endtime: 1632227056.1332355
DEBUG: time: 1632227043.309157, endtime: 1632227056.1332355
DEBUG: time: 1632227048.3146107, endtime: 1632227056.1332355
DEBUG: time: 1632227053.320087, endtime: 1632227056.1332355
DEBUG: Received data from SSH call 
Process killed - no output for 300 seconds. Total running time: 307 seconds. 
DEBUG: [Command returned '0' after 307.20 seconds]
DEBUG: Command: systemctl disable --now --runtime systemd-timesyncd
Status: 0 Output:  
Process killed - no output for 300 seconds. Total running time: 307 seconds.

DEBUG: [Running]$ ssh -l root -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.6 export PA
TH=/usr/sbin:/sbin:/usr/bin:/bin; date +"%Y-%m-%d %T"
DEBUG: time: 1632227063.3388348, endtime: 1632227363.3323236
DEBUG: time: 1632227068.3444843, endtime: 1632227363.3323236
DEBUG: time: 1632227073.3499038, endtime: 1632227363.3323236
DEBUG: Partial data from SSH call: ssh: connect to host 192.168.7.6 port 22: No route to host


Looking at the qmp dump, the logs inside the image look like:

systemd-logind.service: Unexpected error response from GetNameOwner(): Connection terminated
systemd-hostnamed.service: Deactivated successfully
ofono.service: Unexpected error response from GetNameOwner(): Connection terminated
ofono.service: Deactivated successfully
systemd-logind.service: Deactivated successfully
connman.service: Unexpected error response from GetNameOwner(): Connection terminated
connman.service: Deactivated successfully
[and other deactivations follows]
Comment 1 Randy MacLeod 2022-03-03 15:49:51 UTC
Should still be looked into even though it hasn't happened again.