..and the last things to be exec'd are: 16:01:15 exit 1165 0 0.037 ./breakpoint_test 16:01:15 exit 1164 0 0.043 /bin/sh -c for TEST in breakpoint_test step_after_suspend_test; do (./$TEST && echo "selftests: $TEST [PASS]") || echo "selftests: $TEST [FAIL]"; done; 16:01:15 exit 1166 0 0.036 ./breakpoint_test Time Event PID Info Duration Process 16:01:15 fork 1163 parent /bin/sh -c for TEST in breakpoint_test step_after_suspend_test; do (./$TEST && echo "selftests: $TEST [PASS]") || echo "selftests: $TEST [FAIL]"; done; 16:01:15 fork 1167 child /bin/sh -c for TEST in breakpoint_test step_after_suspend_test; do (./$TEST && echo "selftests: $TEST [PASS]") || echo "selftests: $TEST [FAIL]"; done; 16:01:15 fork 1167 parent /bin/sh -c for TEST in breakpoint_test step_after_suspend_test; do (./$TEST && echo "selftests: $TEST [PASS]") || echo "selftests: $TEST [FAIL]"; done; 16:01:15 fork 1168 child /bin/sh -c for TEST in breakpoint_test step_after_suspend_test; do (./$TEST && echo "selftests: $TEST [PASS]") || echo "selftests: $TEST [FAIL]"; done; 16:01:15 exec 1168 ./step_after_suspend_test 16:01:21 fork 333 parent /lib/systemd/systemd-udevd 16:01:21 fork 1169 child /lib/systemd/systemd-udevd 16:01:21 fork 1169 parent /lib/systemd/systemd-udevd 16:01:21 fork 1170 child /lib/systemd/systemd-udevd 16:01:21 fork 333 parent /lib/systemd/systemd-udevd 16:01:21 fork 1171 child /lib/systemd/systemd-udevd
So, I'm basically ssh'ing to the running qemu instance and running on three terminals: dmesg -w, forkstat and fnotifystat to see what happens before it gets stuck -- You received this bug notification because you are a member of Kernel Packages, which is subscribed to linux in Ubuntu. https://bugs.launchpad.net/bugs/1632252 Title: linux autopkgtest kills sshd in testbed Status in linux package in Ubuntu: Confirmed Status in linux source package in Yakkety: Confirmed Bug description: The current linux autopkgtests in yakkety are caught in an eternal "temporary failure" retry loop. They stopped timing out, but in the middle of the tests they kill sshd. Tail of test output: 09:01:00 DEBUG| [stdout] preparing apparmor_2.10.95-4ubuntu5.dsc... done 09:01:00 DEBUG| [stdout] 09:01:40 ERROR| [stderr] Run libapparmor testsuite (with python3) ... ok 09:01:40 ERROR| [stderr] test_parser_testsuite (__main__.ApparmorTestsuites) 09:01:48 DEBUG| [stdout] preparing apparmor_2.10.95-4ubuntu5.dsc... done 09:01:48 DEBUG| [stdout] 09:19:27 ERROR| [stderr] Run parser regression tests ... ok 09:19:27 ERROR| [stderr] test_regression_testsuite (__main__.ApparmorTestsuites) 09:19:33 DEBUG| [stdout] preparing apparmor_2.10.95-4ubuntu5.dsc... done 09:19:33 DEBUG| [stdout] Killed Killed Connection to 10.220.42.236 closed by remote host. Exit request sent. autopkgtest [09:24:42]: ERROR: testbed failure: testbed auxverb failed with exit code 255 Console output during the test: Ubuntu 16.10 autopkgtest ttyS0 autopkgtest login: [ 3512.996305] AppArmor: change_hat: Invalid input '^open' [ 3513.000678] AppArmor: change_hat: Invalid input '^'[ 3513.013965] AppArmor: change_hat: Invalid input, NULL hat and NULL magic [ 3513.019831] AppArmor: change_hat: Invalid input, NULL hat and NULL magic [ 3513.024872] AppArmor: change_hat: Invalid input '^open'AppArmor: change_hat: Invalid input '^' [ 3513.026539] AppArmor: change_hat: Invalid input '^'[ 3638.281408] Out of memory: Kill process 587 (rsyslogd) score 8 or sacrifice child [ 3638.284907] Killed process 587 (rsyslogd) total-vm:107460kB, anon-rss:67892kB, file-rss:2548kB, shmem-rss:0kB [ 3638.306990] Out of memory: Kill process 300 (systemd-journal) score 2 or sacrifice child [ 3638.308985] Killed process 300 (systemd-journal) total-vm:46184kB, anon-rss:460kB, file-rss:3752kB, shmem-rss:15412kB [ 3638.319337] Out of memory: Kill process 2636 (autotest-local) score 1 or sacrifice child [ 3638.320394] Killed process 10325 (autotest-local) total-vm:18092kB, anon-rss:7296kB, file-rss:3544kB, shmem-rss:0kB [ 3638.345419] Out of memory: Kill process 2636 (autotest-local) score 1 or sacrifice child [ 3638.347095] Killed process 2638 (autotest-local) total-vm:17836kB, anon-rss:7160kB, file-rss:1752kB, shmem-rss:0kB [ 3638.368917] Out of memory: Kill process 2636 (autotest-local) score 1 or sacrifice child [ 3638.369954] Killed process 2637 (autotest-local) total-vm:17836kB, anon-rss:7160kB, file-rss:1684kB, shmem-rss:0kB [ 3638.479196] Out of memory: Kill process 2636 (autotest-local) score 1 or sacrifice child [ 3638.482968] Killed process 2636 (autotest-local) total-vm:17836kB, anon-rss:7248kB, file-rss:5864kB, shmem-rss:0kB [ 3638.501050] Out of memory: Kill process 10619 (python) score 1 or sacrifice child [ 3638.502022] Killed process 14752 (make) total-vm:6052kB, anon-rss:196kB, file-rss:2032kB, shmem-rss:0kB [ 3638.511243] Out of memory: Kill process 10619 (python) score 1 or sacrifice child [ 3638.512224] Killed process 10619 (python) total-vm:16752kB, anon-rss:5836kB, file-rss:6148kB, shmem-rss:0kB [ 3638.525104] Out of memory: Kill process 3382 (python) score 0 or sacrifice child [ 3638.526060] Killed process 3382 (python) total-vm:21296kB, anon-rss:3732kB, file-rss:4080kB, shmem-rss:0kB [ 3638.545114] Out of memory: Kill process 553 (haveged) score 0 or sacrifice child [ 3638.546068] Killed process 553 (haveged) total-vm:8028kB, anon-rss:4780kB, file-rss:1468kB, shmem-rss:0kB [ 3638.573449] Out of memory: Kill process 10373 (sshd) score 0 or sacrifice child [ 3638.574353] Killed process 11430 (sshd) total-vm:14444kB, anon-rss:1712kB, file-rss:2960kB, shmem-rss:0kB [ 3638.591696] Out of memory: Kill process 10373 (sshd) score 0 or sacrifice child [ 3638.595493] Killed process 10373 (sshd) total-vm:13460kB, anon-rss:752kB, file-rss:5476kB, shmem-rss:4kB [ 3638.605938] Out of memory: Kill process 898 (sshd) score 0 or sacrifice child [ 3638.607617] Killed process 911 (sshd) total-vm:13804kB, anon-rss:1120kB, file-rss:3184kB, shmem-rss:0kB [ 3638.626950] Out of memory: Kill process 898 (sshd) score 0 or sacrifice child [ 3638.627839] Killed process 898 (sshd) total-vm:13460kB, anon-rss:744kB, file-rss:5436kB, shmem-rss:4kB [ 3638.641735] Out of memory: Kill process 900 (systemd) score 0 or sacrifice child [ 3638.643448] Killed process 901 ((sd-pam)) total-vm:10360kB, anon-rss:1300kB, file-rss:48kB, shmem-rss:0kB [ 3638.652509] Out of memory: Kill process 900 (systemd) score 0 or sacrifice child [ 3638.653656] Killed process 900 (systemd) total-vm:9288kB, anon-rss:640kB, file-rss:4928kB, shmem-rss:0kB [ 3638.665935] Out of memory: Kill process 11431 (bash) score 0 or sacrifice child [ 3638.666823] Killed process 10819 (dmesg) total-vm:4236kB, anon-rss:84kB, file-rss:880kB, shmem-rss:0kB [ 3638.675625] Out of memory: Kill process 11431 (bash) score 0 or sacrifice child [ 3638.676541] Killed process 11431 (bash) total-vm:6656kB, anon-rss:1460kB, file-rss:2956kB, shmem-rss:0kB [ 3638.691069] Out of memory: Kill process 9626 (bash) score 0 or sacrifice child [ 3638.694953] Killed process 10187 (link_subset) total-vm:2420kB, anon-rss:72kB, file-rss:752kB, shmem-rss:0kB While the test ran, I was logged in via ssh and ran "dmesg -w". Tail: egression/apparmor/link_subset" name="/tmp/sdtest.9626-16014-y5JvIx/link_mraCxk" pid=10187 comm="link_subset" requested_mask="l" denied_mask="l" fsuid=0 ouid=0 target="/tmp/sdtest.9626-16014-y5JvIx/target_mrwPixlk" [ 3638.404240] audit: type=1400 audit(1476177816.588:151906): apparmor="DENIED" operation="link" info="link not subset of target" error=-13 profile="/tmp/testlibu87fac/source/yakkety/apparmor-2.10.95/tests/regression/apparmor/link_subset" name="/tmp/sdtest.9626-16014-y5JvIx/link_mCxlk" pid=10187 comm="link_subset" requested_mask="x" denied_mask="x" fsuid=0 ouid=0 target="/tmp/sdtest.9626-16014-y5JvIx/target_mrwPixlk" [ 3638.404254] audit: type=1400 audit(1476177816.588:151907): apparmor="DENIED" operation="link" info="link not subset of target" error=-13 profile="/tmp/testlibu87fac/source/yakkety/apparmor-2.10.95/tests/regression/apparmor/link_subset" name="/tmp/sdtest.9626-16014-y5JvIx/link_mwCxlk" pid=10187 comm="link_subset" requested_mask="x" denied_mask="x" fsuid=0 ouid=0 target="/tmp/sdtest.9626-16014-y5JvIx/target_mrwPixlk" [ 3638.404267] audit: type=1400 audit(1476177816.588:151908): apparmor="DENIED" operation="link" info="link not subset of target" error=-13 profile="/tmp/testlibu87fac/source/yakkety/apparmor-2.10.95/tests/regression/apparmor/link_subset" name="/tmp/sdtest.9626-16014-y5JvIx/link_mrCxlk" pid=10187 comm="link_subset" requested_mask="x" denied_mask="x" fsuid=0 ouid=0 target="/tmp/sdtest.9626-16014-y5JvIx/target_mrwPixlk" [ 3638.404280] audit: type=1400 audit(1476177816.588:151909): apparmor="DENIED" operation="link" info="link not subset of target" error=-13 profile="/tmp/testlibu87fac/source/yakkety/apparmor-2.10.95/tests/regression/apparmor/link_subset" name="/tmp/sdtest.9626-16014-y5JvIx/link_mrwCxlk" pid=10187 comm="link_subset" requested_mask="x" denied_mask="x" fsuid=0 ouid=0 target="/tmp/sdtest.9626-16014-y5JvIx/target_mrwPixlk" [ 3638.404293] audit: type=1400 audit(1476177816.588:151910): apparmor="DENIED" operation="link" info="link not subset of target" error=-13 profile="/tmp/testlibu87fac/source/yakkety/apparmor-2.10.95/tests/regression/apparmor/link_subset" name="/tmp/sdtest.9626-16014-y5JvIx/link_maCxlk" pid=10187 comm="link_subset" requested_mask="x" denied_mask="x" fsuid=0 ouid=0 target="/tmp/sdtest.9626-16014-y5JvIx/target_mrwPixlk" [ 3638.404306] audit: type=1400 audit(1476177816.588:151911): apparmor="DENIED" operation="link" info="link not subset of target" error=-13 profile="/tmp/testlibu87fac/source/yakkety/apparmor-2.10.95/tests/regression/apparmor/link_subset" name="/tmp/sdtest.9626-16014-y5JvIx/link_mraCxlk" pid=10187 comm="link_subset" requested_mask="x" denied_mask="x" fsuid=0 ouid=0 target="/tmp/sdtest.9626-16014-y5JvIx/target_mrwPixlk" [ 3638.404318] audit: type=1400 audit(1476177816.588:151912): apparmor="DENIED" operation="link" profile="/tmp/testlibu87fac/source/yakkety/apparmor-2.10.95/tests/regression/apparmor/link_subset" name="/tmp/sdtest.9626-16014-y5JvIx/link_cx" pid=10187 comm="link_subset" requested_mask="l" denied_mask="l" fsuid=0 ouid=0 target="/tmp/sdtest.9626-16014-y5JvIx/target_mrwPixlk" [ 3638.404330] audit: type=1400 audit(1476177816.588:151913): apparmor="DENIED" operation="link" profile="/tmp/testlibu87fac/source/yakkety/apparmor-2.10.95/tests/regression/apparmor/link_subset" name="/tmp/sdtest.9626-16014-y5JvIx/link_wcx" pid=10187 comm="link_subset" requested_mask="l" denied_mask="l" fsuid=0 ouid=0 target="/tmp/sdtest.9626-16014-y5JvIx/target_mrwPixlk" [ 3638.404342] audit: type=1400 audit(1476177816.588:151914): apparmor="DENIED" operation="link" profile="/tmp/testlibu87fac/source/yakkety/apparmor-2.10.95/tests/regression/apparmor/link_subset" name="/tmp/sdtest.9626-16014-y5JvIx/link_rcx" pid=10187 comm="link_subset" requested_mask="l" denied_mask="l" fsuid=0 ouid=0 target="/tmp/sdtest.9626-16014-y5JvIx/target_mrwPixlk" [ 3638.404354] audit: type=1400 audit(1476177816.588:151915): apparmor="DENIED" operation="link" profile="/tmp/testlibu87fac/source/yakkety/apparmor-2.10.95/tests/regression/apparmor/link_subset" name="/tmp/sdtest.9626-16014-y5JvIx/link_rwcx" pid=10187 comm="link_subset" requested_mask="l" denied_mask="l" fsuid=0 ouid=0 target="/tmp/sdtest.9626-16014-y5JvIx/target_mrwPixlk" [ 3638.404365] audit: type=1400 audit(1476177816.588:151916): apparmor="DENIED" operation="link" profile="/tmp/testlibu87fac/source/yakkety/apparmor-2.10.95/tests/regression/apparmor/link_subset" name="/tmp/sdtest.9626-16014-y5JvIx/link_acx" pid=10187 comm="link_subset" requested_mask="l" denied_mask="l" fsuid=0 ouid=0 target="/tmp/sdtest.9626-16014-y5JvIx/target_mrwPixlk" [ 3638.404377] audit: type=1400 audit(1476177816.588:151917): apparmor="DENIED" operation="link" profile="/tmp/testlibu87fac/source/yakkety/apparmor-2.10.95/tests/regression/apparmor/link_subset" name="/tmp/sdtest.9626-16014-y5JvIx/link_racx" pid=10187 comm="link_subset" requested_mask="l" denied_mask="l" fsuid=0 ouid=0 target="/tmp/sdtest.9626-16014-y5JvIx/target_mrwPixlk" [ 3638.404390] audit: type=1400 audit(1476177816.588:151918): apparmor="DENIED" operation="link" info="link not subset of target" error=-13 profile="/tmp/testlibu87fac/source/yakkety/apparmor-2.10.95/tests/regression/apparmor/link_subset" name="/tmp/sdtest.9626-16014-y5JvIx/link_cxl" pid=10187 comm="link_subset" requested_mask="x" denied_mask="x" fsuid=0 ouid=0 target="/tmp/sdtest.9626-16014-y5JvIx/target_mrwPixlk" [ 3638.404419] audit: type=1400 audit(1476177816.588:151919): apparmor="DENIED" operation="link" info="link not subset of target" error=-13 profile="/tmp/testlibu87fac/source/yakkety/apparmor-2.10.95/tests/regression/apparmor/link_subset" name="/tmp/sdtest.9626-16014-y5JvIx/link_wcxl" pid=10187 comm="link_subset" requested_mask="x" denied_mask="x" fsuid=0 ouid=0 target="/tmp/sdtest.9626-16014-y5JvIx/target_mrwPixlk" [ 3638.404438] audit: type=1400 audit(1476177816.588:151920): apparmor="DENIED" operation="link" info="link not subset of target" error=-13 profile="/tmp/testlibu87fac/source/yakkety/apparmor-2.10.95/tests/regression/apparmor/link_subset" name="/tmp/sdtest.9626-16014-y5JvIx/link_rcxl" pid=10187 comm="link_subset" requested_mask="x" denied_mask="x" fsuid=0 ouid=0 target="/tmp/sdtest.9626-16014-y5JvIx/target_mrwPixlk" [ 3638.404452] audit: type=1400 audit(1476177816.588:151921): apparmor="DENIED" operation="link" info="link not subset of target" error=-13 profile="/tmp/testlibu87fac/source/yakkety/apparmor-2.10.95/tests/regression/apparmor/link_subset" name="/tmp/sdtest.9626-16014-y5JvIx/link_rwcxl" pid=10187 comm="link_subset" requested_mask="x" denied_mask="x" fsuid=0 ouid=0 target="/tmp/sdtest.9626-16014-y5JvIx/target_mrwPixlk" [ 3638.404465] audit: type=1400 audit(1476177816.588:151922): apparmor="DENIED" operation="link" info="link not subset of target" error=-13 profile="/tmp/testlibu87fac/source/yakkety/apparmor-2.10.95/tests/regression/apparmor/link_subset" name="/tmp/sdtest.9626-16014-y5JvIx/link_acxl" pid=10187 comm="link_subset" requested_maspacket_write_wait: Connection to 10.220.42.236: Broken pipe The many DENIED are just expected errors from the AppArmor tests, and the "broken pipe" is from ssh when the testbed side kills sshd (presumably due to OOM?) To manage notifications about this bug go to: https://bugs.launchpad.net/ubuntu/+source/linux/+bug/1632252/+subscriptions -- Mailing list: https://launchpad.net/~kernel-packages Post to : kernel-packages@lists.launchpad.net Unsubscribe : https://launchpad.net/~kernel-packages More help : https://help.launchpad.net/ListHelp
