Build failed in Jenkins: master-openbsc » --disable-iu,--disable-mgcp-transcoding,--enable-smpp,default,osmocom-master #15787

See https://jenkins.osmocom.org/jenkins/job/master-openbsc/IU=--disable-iu,MGCP=--disable-mgcp-transcoding,SMPP=--enable-smpp,a4=default,label=osmocom-master/15787/display/redirect

Changes:

------------------------------------------ [...truncated 512.92 KB...]

ss -tln

State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess LISTEN 0 10 127.0.0.1:4244 0.0.0.0:* LISTEN 0 10 127.0.0.1:4250 0.0.0.0:* LISTEN 0 1 127.0.0.1:5000 0.0.0.0:* LISTEN 0 1 0.0.0.0:5001 0.0.0.0:*

ps xua | grep osmo

build 43756 0.0 0.0 13412 7400 ? S 23:58 0:00 ./src/osmo-bsc_nat/osmo-bsc_nat -l 127.0.0.1 -c /build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg build 43763 0.0 0.0 2576 880 ? S 23:58 0:00 /bin/sh -c ps xua | grep osmo build 43765 0.0 0.0 3700 1776 ? S 23:58 0:00 grep osmo

Socket: in 1 tries, connected to 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 45288), raddr=('127.0.0.1', 4244)> (1 sockets open) MSC is ready at 127.0.0.1 'show msc connection' says: 'MSC is connected: 1' ERROR Socket: closing 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 45288), raddr=('127.0.0.1', 4244)> (0 sockets open) Process returned code: -15 testEnsureNoEnsureModeSet (__main__.TestVTYNAT.testEnsureNoEnsureModeSet) ... Launching: PWD=/build/openbsc './src/osmo-bsc_nat/osmo-bsc_nat' '-l' '127.0.0.1' '-c' '/build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg'

ls -l /proc/43495/fd

total 0 lr-x------ 1 build build 64 Dec 11 23:57 0 -> pipe:[1975096254] l-wx------ 1 build build 64 Dec 11 23:57 1 -> pipe:[1975096255] l-wx------ 1 build build 64 Dec 11 23:57 2 -> pipe:[1975096256] l-wx------ 1 build build 64 Dec 11 23:57 3 -> /dev/null

ss -tn

State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess

ss -tln

State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess LISTEN 0 10 127.0.0.1:4244 0.0.0.0:* LISTEN 0 10 127.0.0.1:4250 0.0.0.0:* LISTEN 0 1 127.0.0.1:5000 0.0.0.0:* LISTEN 0 1 0.0.0.0:5001 0.0.0.0:*

ps xua | grep osmo

build 43766 0.0 0.0 13412 7448 ? S 23:58 0:00 ./src/osmo-bsc_nat/osmo-bsc_nat -l 127.0.0.1 -c /build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg build 43773 0.0 0.0 2576 952 ? S 23:58 0:00 /bin/sh -c ps xua | grep osmo build 43775 0.0 0.0 3700 1868 ? S 23:58 0:00 grep osmo

Socket: in 1 tries, connected to 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 45296), raddr=('127.0.0.1', 4244)> (1 sockets open) Socket: closing 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 45296), raddr=('127.0.0.1', 4244)> (0 sockets open) Process returned code: -15 ok testPrefixTreeLoading (__main__.TestVTYNAT.testPrefixTreeLoading) ... Launching: PWD=/build/openbsc './src/osmo-bsc_nat/osmo-bsc_nat' '-l' '127.0.0.1' '-c' '/build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg'

ls -l /proc/43495/fd

total 0 lr-x------ 1 build build 64 Dec 11 23:57 0 -> pipe:[1975096254] l-wx------ 1 build build 64 Dec 11 23:57 1 -> pipe:[1975096255] l-wx------ 1 build build 64 Dec 11 23:57 2 -> pipe:[1975096256] l-wx------ 1 build build 64 Dec 11 23:57 3 -> /dev/null

ss -tn

State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess

ss -tln

State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess LISTEN 0 10 127.0.0.1:4244 0.0.0.0:* LISTEN 0 10 127.0.0.1:4250 0.0.0.0:* LISTEN 0 1 127.0.0.1:5000 0.0.0.0:* LISTEN 0 1 0.0.0.0:5001 0.0.0.0:*

ps xua | grep osmo

build 43776 0.0 0.0 13412 6856 ? S 23:58 0:00 ./src/osmo-bsc_nat/osmo-bsc_nat -l 127.0.0.1 -c /build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg build 43783 0.0 0.0 2576 928 ? S 23:58 0:00 /bin/sh -c ps xua | grep osmo build 43785 0.0 0.0 3700 1888 ? S 23:58 0:00 grep osmo

Socket: in 1 tries, connected to 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 45306), raddr=('127.0.0.1', 4244)> (1 sockets open) Socket: closing 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 45306), raddr=('127.0.0.1', 4244)> (0 sockets open) Process returned code: -15 ok testRewriteNoRewrite (__main__.TestVTYNAT.testRewriteNoRewrite) ... Launching: PWD=/build/openbsc './src/osmo-bsc_nat/osmo-bsc_nat' '-l' '127.0.0.1' '-c' '/build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg'

ls -l /proc/43495/fd

total 0 lr-x------ 1 build build 64 Dec 11 23:57 0 -> pipe:[1975096254] l-wx------ 1 build build 64 Dec 11 23:57 1 -> pipe:[1975096255] l-wx------ 1 build build 64 Dec 11 23:57 2 -> pipe:[1975096256] l-wx------ 1 build build 64 Dec 11 23:57 3 -> /dev/null

ss -tn

State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess

ss -tln

State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess LISTEN 0 10 127.0.0.1:4244 0.0.0.0:* LISTEN 0 10 127.0.0.1:4250 0.0.0.0:* LISTEN 0 1 127.0.0.1:5000 0.0.0.0:* LISTEN 0 1 0.0.0.0:5001 0.0.0.0:*

ps xua | grep osmo

build 43786 0.0 0.0 13412 7028 ? S 23:58 0:00 ./src/osmo-bsc_nat/osmo-bsc_nat -l 127.0.0.1 -c /build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg build 43793 0.0 0.0 2576 924 ? S 23:58 0:00 /bin/sh -c ps xua | grep osmo build 43795 0.0 0.0 3700 1864 ? S 23:58 0:00 grep osmo

Socket: in 1 tries, connected to 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 45318), raddr=('127.0.0.1', 4244)> (1 sockets open) Socket: closing 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 45318), raddr=('127.0.0.1', 4244)> (0 sockets open) Process returned code: -15 ok testRewritePostNoRewrite (__main__.TestVTYNAT.testRewritePostNoRewrite) ... Launching: PWD=/build/openbsc './src/osmo-bsc_nat/osmo-bsc_nat' '-l' '127.0.0.1' '-c' '/build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg'

ls -l /proc/43495/fd

total 0 lr-x------ 1 build build 64 Dec 11 23:57 0 -> pipe:[1975096254] l-wx------ 1 build build 64 Dec 11 23:57 1 -> pipe:[1975096255] l-wx------ 1 build build 64 Dec 11 23:57 2 -> pipe:[1975096256] l-wx------ 1 build build 64 Dec 11 23:57 3 -> /dev/null

ss -tn

State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess

ss -tln

State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess LISTEN 0 10 127.0.0.1:4244 0.0.0.0:* LISTEN 0 10 127.0.0.1:4250 0.0.0.0:* LISTEN 0 1 127.0.0.1:5000 0.0.0.0:* LISTEN 0 1 0.0.0.0:5001 0.0.0.0:*

ps xua | grep osmo

build 43796 0.0 0.0 13412 7340 ? S 23:58 0:00 ./src/osmo-bsc_nat/osmo-bsc_nat -l 127.0.0.1 -c /build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg build 43803 0.0 0.0 2576 952 ? S 23:58 0:00 /bin/sh -c ps xua | grep osmo build 43805 0.0 0.0 3700 1888 ? S 23:58 0:00 grep osmo

Socket: in 1 tries, connected to 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 45330), raddr=('127.0.0.1', 4244)> (1 sockets open) Socket: closing 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 45330), raddr=('127.0.0.1', 4244)> (0 sockets open) Process returned code: -15 ok testUssdSideChannelProvider (__main__.TestVTYNAT.testUssdSideChannelProvider) ... Launching: PWD=/build/openbsc './src/osmo-bsc_nat/osmo-bsc_nat' '-l' '127.0.0.1' '-c' '/build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg'

ls -l /proc/43495/fd

total 0 lr-x------ 1 build build 64 Dec 11 23:57 0 -> pipe:[1975096254] l-wx------ 1 build build 64 Dec 11 23:57 1 -> pipe:[1975096255] l-wx------ 1 build build 64 Dec 11 23:57 2 -> pipe:[1975096256] l-wx------ 1 build build 64 Dec 11 23:57 3 -> /dev/null

ss -tn

State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess

ss -tln

State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess LISTEN 0 10 127.0.0.1:4244 0.0.0.0:* LISTEN 0 10 127.0.0.1:4250 0.0.0.0:* LISTEN 0 1 127.0.0.1:5000 0.0.0.0:* LISTEN 0 1 0.0.0.0:5001 0.0.0.0:*

ps xua | grep osmo

build 43806 0.0 0.0 13412 6908 ? S 23:58 0:00 ./src/osmo-bsc_nat/osmo-bsc_nat -l 127.0.0.1 -c /build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg build 43813 0.0 0.0 2576 916 ? S 23:58 0:00 /bin/sh -c ps xua | grep osmo build 43815 0.0 0.0 3700 1752 ? S 23:58 0:00 grep osmo

Socket: in 1 tries, connected to 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 45334), raddr=('127.0.0.1', 4244)> (1 sockets open) Connected to 127.0.0.1:5001 Expecting ID_GET request Going to send ID_RESP response Going to send PING request Expecting PONG response Going to shut down connection Expecting EOF Socket: closing 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 45334), raddr=('127.0.0.1', 4244)> (0 sockets open) Process returned code: -15 ok testVtyTree (__main__.TestVTYNAT.testVtyTree) ... Launching: PWD=/build/openbsc './src/osmo-bsc_nat/osmo-bsc_nat' '-l' '127.0.0.1' '-c' '/build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg'

ls -l /proc/43495/fd

total 0 lr-x------ 1 build build 64 Dec 11 23:57 0 -> pipe:[1975096254] l-wx------ 1 build build 64 Dec 11 23:57 1 -> pipe:[1975096255] l-wx------ 1 build build 64 Dec 11 23:57 2 -> pipe:[1975096256] l-wx------ 1 build build 64 Dec 11 23:57 3 -> /dev/null

ss -tn

State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess

ss -tln

State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess LISTEN 0 10 127.0.0.1:4244 0.0.0.0:* LISTEN 0 10 127.0.0.1:4250 0.0.0.0:* LISTEN 0 1 127.0.0.1:5000 0.0.0.0:* LISTEN 0 1 0.0.0.0:5001 0.0.0.0:*

ps xua | grep osmo

build 43816 0.0 0.0 13412 6924 ? S 23:58 0:00 ./src/osmo-bsc_nat/osmo-bsc_nat -l 127.0.0.1 -c /build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg build 43823 0.0 0.0 2576 900 ? S 23:58 0:00 /bin/sh -c ps xua | grep osmo build 43825 0.0 0.0 3700 1848 ? S 23:58 0:00 grep osmo

Socket: in 1 tries, connected to 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 45338), raddr=('127.0.0.1', 4244)> (1 sockets open) Socket: closing 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 45338), raddr=('127.0.0.1', 4244)> (0 sockets open) Process returned code: -15 ok

====================================================================== ERROR: testBSCreload (__main__.TestVTYNAT.testBSCreload) ---------------------------------------------------------------------- Traceback (most recent call last): File "/build/openbsc/tests/./vty_test_runner.py", line 803, in testBSCreload msc_socket, msc = nat_msc_test(self, ip, port, verbose=True) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/build/openbsc/tests/./vty_test_runner.py", line 1047, in nat_msc_test raise Exception("VTY reports MSC is connected, but I haven't" Exception: VTY reports MSC is connected, but I haven't connected yet: '127.0.0.1' 5522

---------------------------------------------------------------------- Ran 33 tests in 80.689s

FAILED (errors=1) make[5]: *** [Makefile:717: python-tests] Error 1 make[4]: *** [Makefile:726: check-local] Error 2 make[5]: Leaving directory '/build/openbsc/tests' make[4]: Leaving directory '/build/openbsc/tests' make[3]: *** [Makefile:575: check-am] Error 2 make[3]: Leaving directory '/build/openbsc/tests' make[2]: *** [Makefile:425: check-recursive] Error 1 make[2]: Leaving directory '/build/openbsc/tests' make[1]: *** [Makefile:457: check-recursive] Error 1 make[1]: Leaving directory '/build/openbsc' make: *** [Makefile:756: check] Error 2 + cat-testlogs.sh Build step 'Execute shell' marked build as failure $ ssh-agent -k unset SSH_AUTH_SOCK; unset SSH_AGENT_PID; echo Agent pid 2385687 killed; [ssh-agent] Stopped. Archiving artifacts