5498 2013-11-06 19:32:52 +0000 connmand running twice 2014-07-31 14:07:21 +0000 1 1 7 Build System, Metadata & Runtime OE-Core core unspecified x86 Multiple VERIFIED FIXED Medium+ normal 1.6 1 nitin.a.kamble ross.burton alexandru.c.georgescu meta.mr.watcher meta.watcher ross.burton sstncr corneliux.stoicescu --- --- No (bug/feature does not impact docs) oldest_to_newest 38617 0 nitin.a.kamble 2013-11-06 19:32:52 +0000 Link to autobuilder rrror log: http://autobuilder.yoctoproject.org:8011/builders/nightly-qa-systemd/builds/229/steps/Running%20Sanity%20Tests/logs/stdio ERROR: Function failed: core-image-sato - FAILED - check the task log and the ssh log ERROR: Logfile of failure stored in: /home/pokybuild/yocto-autobuilder/yocto-slave/nightly-qa-systemd/build/build/tmp/work/qemux86_64-poky-linux/core-image-sato/1.0-r0/temp/log.do_testimage.29964 Log data follows: | DEBUG: Executing python function do_testimage | NOTE: Created listening socket for qemu serial console on: 127.0.0.1:51333 | NOTE: DISPLAY value: :1 | NOTE: rootfs file: /home/pokybuild/yocto-autobuilder/yocto-slave/nightly-qa-systemd/build/build/tmp/work/qemux86_64-poky-linux/core-image-sato/1.0-r0/testimage/core-image-sato-qemux86-64-testimage.ext3 | NOTE: Qemu log file: /home/pokybuild/yocto-autobuilder/yocto-slave/nightly-qa-systemd/build/build/tmp/work/qemux86_64-poky-linux/core-image-sato/1.0-r0/testimage/qemu_boot_log.20131106162023 | NOTE: SSH log file: /home/pokybuild/yocto-autobuilder/yocto-slave/nightly-qa-systemd/build/build/tmp/work/qemux86_64-poky-linux/core-image-sato/1.0-r0/testimage/ssh_target_log.20131106162023 | NOTE: runqemu started, pid is 31054 | NOTE: waiting at most 60 seconds for qemu pid | NOTE: qemu started - qemu procces pid is 31432 | NOTE: IP found: 192.168.7.2 | NOTE: Waiting at most 1500 seconds for login banner | NOTE: Connection from 127.0.0.1:58348 | NOTE: Reached login banner | NOTE: Test modules ['oeqa.runtime.ping', 'oeqa.runtime.ssh', 'oeqa.runtime.df', 'oeqa.runtime.connman', 'oeqa.runtime.syslog', 'oeqa.runtime.xorg', 'oeqa.runtime.scp', 'oeqa.runtime.vnc', 'oeqa.runtime.date', 'oeqa.runtime.rpm', 'oeqa.runtime.smart', 'oeqa.runtime.dmesg', 'oeqa.runtime.systemd'] | NOTE: Found 46 tests | test_ping (oeqa.runtime.ping.PingTest) ... ok | test_ssh (oeqa.runtime.ssh.SshTest) ... ok | test_df (oeqa.runtime.df.DfTest) ... ok | test_connmand_help (oeqa.runtime.connman.ConnmanTest) ... ok | test_connmand_running (oeqa.runtime.connman.ConnmanTest) ... ok | test_connmand_unique (oeqa.runtime.connman.ConnmanTest) ... FAIL | test_syslog_help (oeqa.runtime.syslog.SyslogTest) ... ok | test_syslog_running (oeqa.runtime.syslog.SyslogTest) ... ok | test_syslog_logger (oeqa.runtime.syslog.SyslogTestConfig) ... ok | test_syslog_restart (oeqa.runtime.syslog.SyslogTestConfig) ... ok | test_syslog_startup_config (oeqa.runtime.syslog.SyslogTestConfig) ... skipped 'Not appropiate for systemd image' | test_xorg_error (oeqa.runtime.xorg.XorgTest) ... ok | test_xorg_running (oeqa.runtime.xorg.XorgTest) ... ok | test_scp_file (oeqa.runtime.scp.ScpTest) ... ok | test_vnc (oeqa.runtime.vnc.VNCTest) ... ok | test_date (oeqa.runtime.date.DateTest) ... ok | test_rpm_help (oeqa.runtime.rpm.RpmBasicTest) ... ok | test_rpm_query (oeqa.runtime.rpm.RpmBasicTest) ... ok | test_rpm_install (oeqa.runtime.rpm.RpmInstallRemoveTest) ... ok | test_rpm_remove (oeqa.runtime.rpm.RpmInstallRemoveTest) ... FAIL | test_smart_help (oeqa.runtime.smart.SmartBasicTest) ... FAIL | test_smart_info (oeqa.runtime.smart.SmartBasicTest) ... skipped 'Testcase dependency not met: test_smart_help' | test_smart_query (oeqa.runtime.smart.SmartBasicTest) ... skipped 'Testcase dependency not met: test_smart_help' | test_smart_search (oeqa.runtime.smart.SmartBasicTest) ... skipped 'Testcase dependency not met: test_smart_help' | test_smart_stats (oeqa.runtime.smart.SmartBasicTest) ... skipped 'Testcase dependency not met: test_smart_help' | test_smart_version (oeqa.runtime.smart.SmartBasicTest) ... skipped 'Testcase dependency not met: test_smart_help' | test_smart_channel (oeqa.runtime.smart.SmartRepoTest) ... skipped 'Testcase dependency not met: test_smart_help' | test_smart_channel_add (oeqa.runtime.smart.SmartRepoTest) ... skipped 'Testcase dependency not met: test_smart_help' | test_smart_channel_help (oeqa.runtime.smart.SmartRepoTest) ... skipped 'Testcase dependency not met: test_smart_help' | test_smart_channel_list (oeqa.runtime.smart.SmartRepoTest) ... skipped 'Testcase dependency not met: test_smart_help' | test_smart_channel_rpmsys (oeqa.runtime.smart.SmartRepoTest) ... skipped 'Testcase dependency not met: test_smart_help' | test_smart_channel_show (oeqa.runtime.smart.SmartRepoTest) ... skipped 'Testcase dependency not met: test_smart_help' | test_smart_install (oeqa.runtime.smart.SmartRepoTest) ... skipped 'Testcase dependency not met: test_smart_channel_add' | test_smart_install_dependency (oeqa.runtime.smart.SmartRepoTest) ... skipped 'Testcase dependency not met: test_smart_install' | test_smart_install_from_disk (oeqa.runtime.smart.SmartRepoTest) ... skipped 'Testcase dependency not met: test_smart_channel_add' | test_smart_install_from_http (oeqa.runtime.smart.SmartRepoTest) ... skipped 'Testcase dependency not met: test_smart_channel_add' | test_smart_reinstall (oeqa.runtime.smart.SmartRepoTest) ... skipped 'Testcase dependency not met: test_smart_install' | test_dmesg (oeqa.runtime.dmesg.DmesgTest) ... FAIL | test_systemd_version (oeqa.runtime.systemd.SystemdBasicTest) ... FAIL | test_systemd_disable (oeqa.runtime.systemd.SystemdTests) ... FAIL | test_systemd_enable (oeqa.runtime.systemd.SystemdTests) ... skipped 'Testcase dependency not met: test_systemd_version' | test_systemd_failed (oeqa.runtime.systemd.SystemdTests) ... skipped 'Testcase dependency not met: test_systemd_version' | test_systemd_list (oeqa.runtime.systemd.SystemdTests) ... skipped 'Testcase dependency not met: test_systemd_version' | test_systemd_service (oeqa.runtime.systemd.SystemdTests) ... skipped 'Testcase dependency not met: test_systemd_version' | test_systemd_start (oeqa.runtime.systemd.SystemdTests) ... skipped 'Testcase dependency not met: test_systemd_version' | test_systemd_stop (oeqa.runtime.systemd.SystemdTests) ... skipped 'Testcase dependency not met: test_systemd_service' | | ====================================================================== | FAIL: test_connmand_unique (oeqa.runtime.connman.ConnmanTest) | ---------------------------------------------------------------------- | Traceback (most recent call last): | File "/home/pokybuild/yocto-autobuilder/yocto-slave/nightly-qa-systemd/build/meta/lib/oeqa/utils/decorators.py", line 48, in wrapped_f | return f(*args) | File "/home/pokybuild/yocto-autobuilder/yocto-slave/nightly-qa-systemd/build/meta/lib/oeqa/runtime/connman.py", line 27, in test_connmand_unique | self.assertEqual(output, "1", msg="more than one connmand running in background, ps output: %s\n%s" % (output, self.target.run(oeRuntimeTest.pscmd)[1])) | AssertionError: '2' != '1' : more than one connmand running in background, ps output: 2 | PID USER VSZ STAT COMMAND | 1 root 33132 S {systemd} /sbin/init | 2 root 0 SW [kthreadd] | 3 root 0 SW [ksoftirqd/0] | 4 root 0 SW [kworker/0:0] | 5 root 0 SW< [kworker/0:0H] | 6 root 0 SW [kworker/u2:0] | 7 root 0 SW [migration/0] | 8 root 0 SW [rcu_preempt] | 9 root 0 SW [rcu_bh] | 10 root 0 SW [rcu_sched] | 11 root 0 SW< [khelper] | 12 root 0 SW [kdevtmpfs] | 13 root 0 SW< [netns] | 14 root 0 SW< [writeback] | 15 root 0 SW< [bioset] | 16 root 0 SW< [kblockd] | 17 root 0 SW< [ata_sff] | 18 root 0 SW [khubd] | 19 root 0 SW< [md] | 20 root 0 SW [kworker/0:1] | 21 root 0 SW< [rpciod] | 22 root 0 SW [kswapd0] | 23 root 0 SW [fsnotify_mark] | 24 root 0 SW< [nfsiod] | 25 root 0 SW< [bioset] | 26 root 0 SW< [crypto] | 34 root 0 SW [kworker/u2:1] | 35 root 0 SW< [kworker/0:1H] | 36 root 0 SW< [kpsmoused] | 37 root 0 SW [kworker/0:2] | 40 root 0 SW< [deferwq] | 41 root 0 SW [kjournald] | 48 root 85924 S /lib/systemd/systemd-journald | 73 root 32016 S /lib/systemd/systemd-udevd | 105 root 4780 S /sbin/syslogd -n -C | 106 root 4716 S /sbin/klogd -n | 117 messageb 15376 S /usr/bin/dbus-daemon --system --address=systemd: --n | 144 root 15880 S xinit /etc/X11/Xsession -- /usr/bin/Xorg :0 -br -pn | 172 distcc 23856 S N /usr/bin/distccd --pid-file=/var/run/distcc.pid --da | 176 distcc 23856 S N /usr/bin/distccd --pid-file=/var/run/distcc.pid --da | 177 root 8464 S /sbin/agetty --noclear tty1 | 178 root 79888 S < /usr/bin/Xorg :0 -br -pn | 179 distcc 23856 S N /usr/bin/distccd --pid-file=/var/run/distcc.pid --da | 182 distcc 23856 S N /usr/bin/distccd --pid-file=/var/run/distcc.pid --da | 197 root 8464 S /sbin/agetty -s ttyS0 115200 vt102 | 246 root 10928 S /usr/sbin/dropbear -r /etc/dropbear/dropbear_rsa_hos | 331 root 23980 S /lib/systemd/systemd-logind | 348 root 35024 S /usr/sbin/connmand -n | 349 root 54420 S matchbox-window-manager -theme Sato -use_desktop_mod | 358 root 15888 S dbus-launch --sh-syntax --exit-with-session | 363 root 15244 S /usr/bin/dbus-daemon --fork --print-pid 4 --print-ad | 371 root 105m S connman-applet | 377 root 0 Z [gsettings-data-] | 380 root 114m S matchbox-desktop | 381 root 127m S matchbox-panel --titlebar --start-applets showdeskto | 383 root 43480 S /usr/lib/gconf/gconfd-2 | 384 root 97624 S /usr/bin/settings-daemon | 388 root 35024 S /usr/sbin/connmand | 407 root 15156 S /usr/sbin/dropbear -r /etc/dropbear/dropbear_rsa_hos | 408 root 9348 S sh -c . /etc/profile; ps | 409 root 8948 R ps | | ====================================================================== | FAIL: test_rpm_remove (oeqa.runtime.rpm.RpmInstallRemoveTest) | ---------------------------------------------------------------------- | Traceback (most recent call last): | File "/home/pokybuild/yocto-autobuilder/yocto-slave/nightly-qa-systemd/build/meta/lib/oeqa/utils/decorators.py", line 48, in wrapped_f | return f(*args) | File "/home/pokybuild/yocto-autobuilder/yocto-slave/nightly-qa-systemd/build/meta/lib/oeqa/runtime/rpm.py", line 44, in test_rpm_remove | self.assertEqual(status, 0, msg="Failed to remove rpm-doc package: %s" % output) | AssertionError: 255 != 0 : Failed to remove rpm-doc package: | [!!! SSH command timed out after 300 seconds and it was killed] | | ====================================================================== | FAIL: test_smart_help (oeqa.runtime.smart.SmartBasicTest) | ---------------------------------------------------------------------- | Traceback (most recent call last): | File "/home/pokybuild/yocto-autobuilder/yocto-slave/nightly-qa-systemd/build/meta/lib/oeqa/utils/decorators.py", line 48, in wrapped_f | return f(*args) | File "/home/pokybuild/yocto-autobuilder/yocto-slave/nightly-qa-systemd/build/meta/lib/oeqa/runtime/smart.py", line 28, in test_smart_help | self.smart('--help') | File "/home/pokybuild/yocto-autobuilder/yocto-slave/nightly-qa-systemd/build/meta/lib/oeqa/utils/decorators.py", line 48, in wrapped_f | return f(*args) | File "/home/pokybuild/yocto-autobuilder/yocto-slave/nightly-qa-systemd/build/meta/lib/oeqa/runtime/smart.py", line 20, in smart | self.assertEqual(status, expected, message) | AssertionError: 255 != 0 : smart --help | ssh: connect to host 192.168.7.2 port 22: No route to host | | ====================================================================== | FAIL: test_dmesg (oeqa.runtime.dmesg.DmesgTest) | ---------------------------------------------------------------------- | Traceback (most recent call last): | File "/home/pokybuild/yocto-autobuilder/yocto-slave/nightly-qa-systemd/build/meta/lib/oeqa/utils/decorators.py", line 48, in wrapped_f | return f(*args) | File "/home/pokybuild/yocto-autobuilder/yocto-slave/nightly-qa-systemd/build/meta/lib/oeqa/runtime/dmesg.py", line 11, in test_dmesg | self.assertEqual(status, 1, msg = "Error messages in dmesg log: %s" % output) | AssertionError: 255 != 1 : Error messages in dmesg log: ssh: connect to host 192.168.7.2 port 22: No route to host | | ====================================================================== | FAIL: test_systemd_version (oeqa.runtime.systemd.SystemdBasicTest) | ---------------------------------------------------------------------- | Traceback (most recent call last): | File "/home/pokybuild/yocto-autobuilder/yocto-slave/nightly-qa-systemd/build/meta/lib/oeqa/utils/decorators.py", line 48, in wrapped_f | return f(*args) | File "/home/pokybuild/yocto-autobuilder/yocto-slave/nightly-qa-systemd/build/meta/lib/oeqa/runtime/systemd.py", line 17, in test_systemd_version | self.assertEqual(status, 0, msg="status and output: %s and %s" % (status,output)) | AssertionError: 255 != 0 : status and output: 255 and ssh: connect to host 192.168.7.2 port 22: No route to host | | ====================================================================== | FAIL: test_systemd_disable (oeqa.runtime.systemd.SystemdTests) | ---------------------------------------------------------------------- | Traceback (most recent call last): | File "/home/pokybuild/yocto-autobuilder/yocto-slave/nightly-qa-systemd/build/meta/lib/oeqa/utils/decorators.py", line 48, in wrapped_f | return f(*args) | File "/home/pokybuild/yocto-autobuilder/yocto-slave/nightly-qa-systemd/build/meta/lib/oeqa/runtime/systemd.py", line 54, in test_systemd_disable | self.assertEqual(output, 'disabled', msg="machineid.service service could not be disabled. Status and output: %s and %s" % (status, output)) | AssertionError: 'ssh: connect to host 192.168.7.2 port 22: No route to host' != 'disabled' : machineid.service service could not be disabled. Status and output: 255 and ssh: connect to host 192.168.7.2 port 22: No route to host | | ---------------------------------------------------------------------- | Ran 46 tests in 539.391s | | FAILED (failures=6, skipped=23) | NOTE: Sending SIGTERM to runqemu | DEBUG: Python function do_testimage finished | ERROR: Function failed: core-image-sato - FAILED - check the task log and the ssh log NOTE: recipe core-image-sato-1.0-r0: task do_testimage: Failed 38689 1 ross.burton 2013-11-08 16:42:25 +0000 Looks like connman got in a panic and eventually the networking was killed, so the rest of the tests failed. 39323 2 ross.burton 2013-12-10 15:50:37 +0000 I figured out why it appeared to be running twice, and fixed the broken test case in 80ef721140c79e29430d0a5692a5c176db0061e6. 44825 3 alexandru.c.georgescu 2014-07-30 08:44:02 +0000 Corneliu, can you verify this when we are running the next weekly? 44860 4 corneliux.stoicescu 2014-07-31 14:07:21 +0000 Verified with 1.7 M2 RC1.