See <https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/1631/display/redirect> Changes: ------------------------------------------ [...truncated 357.55 KiB...] RSPRO_SRV0(22)@15834addcc47: Established a new IPA connection (conn_id=2) RSPRO_SRV0(22)@15834addcc47: CCM Tx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_UNITID (8) } } } } RSPRO_SRV0(22)@15834addcc47: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(22)@15834addcc47: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO_SRV0(22)@15834addcc47: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 19, tag := IPAC_IDTAG_UNITNAME (1), data := '30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } } } } RSPRO_SRV0(22)@15834addcc47: IPA ID RESP: { { len := 19, tag := IPAC_IDTAG_UNITNAME (1), data := '30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } } RSPRO_SRV0(22)@15834addcc47: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(22)@15834addcc47: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } MTC@15834addcc47: setverdict(pass): none -> pass MTC@15834addcc47: setverdict(pass): pass -> pass, component reason not changed RSPRO0(23)@15834addcc47: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } MTC@15834addcc47: Warning: Re-starting timer T, which is already active (running or expired). RSPRO0(23)@15834addcc47: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } MTC@15834addcc47: setverdict(pass): pass -> pass, component reason not changed MTC@15834addcc47: setverdict(pass): pass -> pass, component reason not changed RSPRO0(23)@15834addcc47: IPA: Closed MTC@15834addcc47: setverdict(pass): pass -> pass, component reason not changed MTC@15834addcc47: setverdict(pass): pass -> pass reason: ""RemsimBankd_Tests.ttcn:258 : "", component reason not changed MTC@15834addcc47: Stopping testcase execution from "RemsimBankd_Tests.ttcn":258 RSPRO0(23)@15834addcc47: Final verdict of PTC: none RSPRO_SRV0(22)@15834addcc47: Final verdict of PTC: none MTC@15834addcc47: Setting final verdict of the test case. MTC@15834addcc47: Local verdict of MTC: pass MTC@15834addcc47: Local verdict of PTC RSPRO_SRV0(22): none (pass -> pass) MTC@15834addcc47: Local verdict of PTC RSPRO0(23): none (pass -> pass) MTC@15834addcc47: Test case TC_createMapping_connectClient_changeMapping finished. Verdict: pass MTC@15834addcc47: Starting external command `../ttcn3-tcpdump-stop.sh RemsimBankd_Tests.TC_createMapping_connectClient_changeMapping pass'. Sat Jan 18 03:19:05 UTC 2025 ====== RemsimBankd_Tests.TC_createMapping_connectClient_changeMapping pass ====== Waiting for packet dumper to finish... 0 (prev_count=-1, count=2312) Waiting for packet dumper to finish... 1 (prev_count=2312, count=4096) MTC@15834addcc47: External command `../ttcn3-tcpdump-stop.sh RemsimBankd_Tests.TC_createMapping_connectClient_changeMapping pass' was executed successfully (exit status: 0). MTC@15834addcc47: Starting external command `../ttcn3-tcpdump-start.sh RemsimBankd_Tests.TC_createMapping_connectClient_recreateMapping'. ------ RemsimBankd_Tests.TC_createMapping_connectClient_recreateMapping ------ Sat Jan 18 03:19:07 UTC 2025 NOTE: unable to use dumpcap due to missing capabilities or suid bit /usr/bin/tcpdump -U -s 1520 -n -i any -w "/data/RemsimBankd_Tests.TC_createMapping_connectClient_recreateMapping.pcap" >/data/RemsimBankd_Tests.TC_createMapping_connectClient_recreateMapping.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@15834addcc47: External command `../ttcn3-tcpdump-start.sh RemsimBankd_Tests.TC_createMapping_connectClient_recreateMapping' was executed successfully (exit status: 0). MTC@15834addcc47: Test case TC_createMapping_connectClient_recreateMapping started. RSPRO_SRV0(24)@15834addcc47: Established a new IPA connection (conn_id=2) RSPRO_SRV0(24)@15834addcc47: CCM Tx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_UNITID (8) } } } } RSPRO_SRV0(24)@15834addcc47: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(24)@15834addcc47: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO_SRV0(24)@15834addcc47: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 19, tag := IPAC_IDTAG_UNITNAME (1), data := '30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } } } } RSPRO_SRV0(24)@15834addcc47: IPA ID RESP: { { len := 19, tag := IPAC_IDTAG_UNITNAME (1), data := '30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } } RSPRO_SRV0(24)@15834addcc47: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(24)@15834addcc47: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } MTC@15834addcc47: setverdict(pass): none -> pass MTC@15834addcc47: setverdict(pass): pass -> pass, component reason not changed RSPRO0(25)@15834addcc47: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } MTC@15834addcc47: Warning: Re-starting timer T, which is already active (running or expired). RSPRO0(25)@15834addcc47: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } MTC@15834addcc47: setverdict(pass): pass -> pass, component reason not changed MTC@15834addcc47: setverdict(pass): pass -> pass, component reason not changed MTC@15834addcc47: setverdict(pass): pass -> pass, component reason not changed MTC@15834addcc47: setverdict(pass): pass -> pass reason: ""RemsimBankd_Tests.ttcn:298 : "", component reason not changed MTC@15834addcc47: Stopping testcase execution from "RemsimBankd_Tests.ttcn":298 RSPRO_SRV0(24)@15834addcc47: Final verdict of PTC: none RSPRO0(25)@15834addcc47: Final verdict of PTC: none MTC@15834addcc47: Setting final verdict of the test case. MTC@15834addcc47: Local verdict of MTC: pass MTC@15834addcc47: Local verdict of PTC RSPRO_SRV0(24): none (pass -> pass) MTC@15834addcc47: Local verdict of PTC RSPRO0(25): none (pass -> pass) MTC@15834addcc47: Test case TC_createMapping_connectClient_recreateMapping finished. Verdict: pass MTC@15834addcc47: Starting external command `../ttcn3-tcpdump-stop.sh RemsimBankd_Tests.TC_createMapping_connectClient_recreateMapping pass'. Sat Jan 18 03:19:21 UTC 2025 ====== RemsimBankd_Tests.TC_createMapping_connectClient_recreateMapping pass ====== Waiting for packet dumper to finish... 0 (prev_count=-1, count=0) MTC@15834addcc47: External command `../ttcn3-tcpdump-stop.sh RemsimBankd_Tests.TC_createMapping_connectClient_recreateMapping pass' was executed successfully (exit status: 0). MTC@15834addcc47: Starting external command `../ttcn3-tcpdump-start.sh RemsimBankd_Tests.TC_createMapping_exchangeTPDU'. ------ RemsimBankd_Tests.TC_createMapping_exchangeTPDU ------ Sat Jan 18 03:19:22 UTC 2025 NOTE: unable to use dumpcap due to missing capabilities or suid bit /usr/bin/tcpdump -U -s 1520 -n -i any -w "/data/RemsimBankd_Tests.TC_createMapping_exchangeTPDU.pcap" >/data/RemsimBankd_Tests.TC_createMapping_exchangeTPDU.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@15834addcc47: External command `../ttcn3-tcpdump-start.sh RemsimBankd_Tests.TC_createMapping_exchangeTPDU' was executed successfully (exit status: 0). MTC@15834addcc47: Test case TC_createMapping_exchangeTPDU started. RSPRO_SRV0(26)@15834addcc47: Established a new IPA connection (conn_id=2) RSPRO_SRV0(26)@15834addcc47: CCM Tx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_UNITID (8) } } } } RSPRO_SRV0(26)@15834addcc47: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(26)@15834addcc47: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO_SRV0(26)@15834addcc47: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 19, tag := IPAC_IDTAG_UNITNAME (1), data := '30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } } } } RSPRO_SRV0(26)@15834addcc47: IPA ID RESP: { { len := 19, tag := IPAC_IDTAG_UNITNAME (1), data := '30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } } RSPRO_SRV0(26)@15834addcc47: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(26)@15834addcc47: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } MTC@15834addcc47: setverdict(pass): none -> pass MTC@15834addcc47: setverdict(pass): pass -> pass, component reason not changed RSPRO0(27)@15834addcc47: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } MTC@15834addcc47: Warning: Re-starting timer T, which is already active (running or expired). RSPRO0(27)@15834addcc47: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } MTC@15834addcc47: setverdict(pass): pass -> pass, component reason not changed MTC@15834addcc47: setverdict(pass): pass -> pass, component reason not changed MTC@15834addcc47: setverdict(pass): pass -> pass, component reason not changed MTC@15834addcc47: setverdict(pass): pass -> pass, component reason not changed MTC@15834addcc47: setverdict(pass): pass -> pass, component reason not changed MTC@15834addcc47: setverdict(pass): pass -> pass, component reason not changed MTC@15834addcc47: setverdict(pass): pass -> pass, component reason not changed MTC@15834addcc47: setverdict(pass): pass -> pass, component reason not changed MTC@15834addcc47: setverdict(pass): pass -> pass, component reason not changed MTC@15834addcc47: setverdict(pass): pass -> pass, component reason not changed MTC@15834addcc47: setverdict(pass): pass -> pass, component reason not changed MTC@15834addcc47: setverdict(pass): pass -> pass, component reason not changed MTC@15834addcc47: setverdict(pass): pass -> pass reason: ""RemsimBankd_Tests.ttcn:407 : "", component reason not changed MTC@15834addcc47: Stopping testcase execution from "RemsimBankd_Tests.ttcn":407 RSPRO0(27)@15834addcc47: Final verdict of PTC: none RSPRO_SRV0(26)@15834addcc47: Final verdict of PTC: none MTC@15834addcc47: Setting final verdict of the test case. MTC@15834addcc47: Local verdict of MTC: pass MTC@15834addcc47: Local verdict of PTC RSPRO_SRV0(26): none (pass -> pass) MTC@15834addcc47: Local verdict of PTC RSPRO0(27): none (pass -> pass) MTC@15834addcc47: Test case TC_createMapping_exchangeTPDU finished. Verdict: pass MTC@15834addcc47: Starting external command `../ttcn3-tcpdump-stop.sh RemsimBankd_Tests.TC_createMapping_exchangeTPDU pass'. Sat Jan 18 03:19:32 UTC 2025 ====== RemsimBankd_Tests.TC_createMapping_exchangeTPDU pass ====== Waiting for packet dumper to finish... 0 (prev_count=-1, count=0) MTC@15834addcc47: External command `../ttcn3-tcpdump-stop.sh RemsimBankd_Tests.TC_createMapping_exchangeTPDU pass' was executed successfully (exit status: 0). MC@15834addcc47: Test execution finished. Execution of [EXECUTE] section finished. emtc MC@15834addcc47: Terminating MTC. MC@15834addcc47: MTC terminated. MC2> exit MC@15834addcc47: Shutting down session. MC@15834addcc47: Shutdown complete. No expected results found, not comparing outcome. (/osmo-ttcn3-hacks/remsim/expected-results.xml) + exit_code=0 + /osmo-ttcn3-hacks/log_merge.sh REMSIM_Tests --rm Generated REMSIM_Tests.TC_clientConnect_createMapping.merged Generated REMSIM_Tests.TC_clientConnect_missingSlot.merged Generated REMSIM_Tests.TC_clientConnect_unknown.merged Generated REMSIM_Tests.TC_connectBankReq.merged Generated REMSIM_Tests.TC_connectBankReq_timeout.merged Generated REMSIM_Tests.TC_createMapping.merged Generated REMSIM_Tests.TC_createMapping_busySlot.merged Generated REMSIM_Tests.TC_createMapping_clientConnect.merged Generated REMSIM_Tests.TC_createMapping_clientReconnect.merged Generated REMSIM_Tests.TC_createMapping_connectClient_changeMapping.merged Generated REMSIM_Tests.TC_createMapping_connectClient_recreateMapping.merged Generated REMSIM_Tests.TC_createMapping_exchangeTPDU.merged Generated REMSIM_Tests.TC_createMapping_invalidBank.merged Generated REMSIM_Tests.TC_createMapping_invalidSlot.merged Generated REMSIM_Tests.TC_removeMapping.merged Generated REMSIM_Tests.TC_removeMapping_connected.merged Generated REMSIM_Tests.TC_removeMapping_unknownMap.merged Removing Input log files !!! + exit 0 + docker_kill_wait jenkins-ttcn3-remsim-test-1631-bankd + docker kill jenkins-ttcn3-remsim-test-1631-bankd jenkins-ttcn3-remsim-test-1631-bankd + docker wait jenkins-ttcn3-remsim-test-1631-bankd 137 + echo Changing to client configuration Changing to client configuration + cp client/REMSIM_Tests.cfg <https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/1631/artifact/logs/remsim-tester/> cp: error copying 'client/REMSIM_Tests.cfg' to '<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/1631/artifact/logs/remsim-tester/REMSIM_Tests.cfg'>: No space left on device + clean_up_common + set +e + set +x ### Clean up ### + trap - EXIT INT TERM 0 + type clean_up + network_clean + docker network inspect ttcn3-remsim-test-25 + + grep Namecut -d : -f2 + awk -F" NR>1{print $2} + local containers= + [ -n ] + network_remove + set +x Removing network ttcn3-remsim-test-25 + docker network remove ttcn3-remsim-test-25 ttcn3-remsim-test-25 + rm -rf <https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/1631/artifact/logs/unix> + fix_perms + set +x Fixing permissions + id -u + id -g + docker run --rm -v <https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/logs>:/data -v <https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_cache>:/cache --name jenkins-ttcn3-remsim-test-1631-cleaner debian:bookworm sh -e -x -c chmod -R a+rX /data/ /cache/ chown -R 1000:1000 /data /cache + chmod -R a+rX /data/ /cache/ + chown -R 1000:1000 /data /cache + collect_logs + cat <https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/1631/artifact/logs/remsim-tester/junit-xml-bankd-21.log> <https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/1631/artifact/logs/remsim-tester/junit-xml-server-21.log> <?xml version="1.0"?> <testsuite name='Titan' tests='17' failures='1' errors='0' skipped='0' inconc='0' time='213.00'> <testcase classname='RemsimBankd_Tests' name='TC_connectBankReq_timeout' time='18.217740'/> <testcase classname='RemsimBankd_Tests' name='TC_connectBankReq' time='6.997453'/> <testcase classname='RemsimBankd_Tests' name='TC_createMapping' time='8.040675'/> <testcase classname='RemsimBankd_Tests' name='TC_createMapping_busySlot' time='7.995860'/> <testcase classname='RemsimBankd_Tests' name='TC_createMapping_invalidSlot' time='7.994323'/> <testcase classname='RemsimBankd_Tests' name='TC_createMapping_invalidBank' time='8.004807'/> <testcase classname='RemsimBankd_Tests' name='TC_removeMapping_unknownMap' time='7.955762'/> <testcase classname='RemsimBankd_Tests' name='TC_removeMapping' time='7.949906'/> <testcase classname='RemsimBankd_Tests' name='TC_clientConnect_missingSlot' time='0.005595'/> <testcase classname='RemsimBankd_Tests' name='TC_clientConnect_unknown' time='0.020197'/> <testcase classname='RemsimBankd_Tests' name='TC_clientConnect_createMapping' time='11.852679'/> <testcase classname='RemsimBankd_Tests' name='TC_createMapping_clientConnect' time='8.022203'/> <testcase classname='RemsimBankd_Tests' name='TC_createMapping_clientReconnect' time='18.018888'/> <testcase classname='RemsimBankd_Tests' name='TC_removeMapping_connected' time='22.029481'> <failure type='fail-verdict'>Timeout expecting disconnect RemsimBankd_Tests.ttcn:429 RemsimBankd_Tests control part RemsimBankd_Tests.ttcn:347 TC_removeMapping_connected testcase </failure> </testcase> <testcase classname='RemsimBankd_Tests' name='TC_createMapping_connectClient_changeMapping' time='8.026049'/> <testcase classname='RemsimBankd_Tests' name='TC_createMapping_connectClient_recreateMapping' time='13.043905'/> <testcase classname='RemsimBankd_Tests' name='TC_createMapping_exchangeTPDU' time='9.153860'/> </testsuite> <?xml version="1.0"?> <testsuite name='Titan' tests='15' failures='2' errors='0' skipped='0' inconc='0' time='60.00'> <testcase classname='RemsimServer_Tests' name='TC_connect_and_nothing' time='10.014368'/> <testcase classname='RemsimServer_Tests' name='TC_connect_client' time='0.049743'/> <testcase classname='RemsimServer_Tests' name='TC_connect_client_duplicate' time='1.099737'/> <testcase classname='RemsimServer_Tests' name='TC_connect_bank' time='0.050469'/> <testcase classname='RemsimServer_Tests' name='TC_connect_bank_duplicate' time='2.103906'/> <testcase classname='RemsimServer_Tests' name='TC_slotmap_add' time='0.003850'/> <testcase classname='RemsimServer_Tests' name='TC_slotmap_add_conn_cl_b' time='0.051769'/> <testcase classname='RemsimServer_Tests' name='TC_slotmap_add_out_of_range' time='0.003606'/> <testcase classname='RemsimServer_Tests' name='TC_conn_cl_b_slotmap_add' time='0.053451'> <failure type='fail-verdict'>Unexpected slotmaps: { clients := omit, banks := omit, slotmaps := { { bank := { bankId := 1, slotNr := 2 }, client := { clientId := 3, slotNr := 4 }, state := UNACKNOWLEDGED (1) } } } RemsimServer_Tests.ttcn:605 RemsimServer_Tests control part RemsimServer_Tests.ttcn:402 TC_conn_cl_b_slotmap_add testcase </failure> </testcase> <testcase classname='RemsimServer_Tests' name='TC_slotmap_del_new' time='0.002595'/> <testcase classname='RemsimServer_Tests' name='TC_slotmap_del_nonexistant' time='0.006929'/> <testcase classname='RemsimServer_Tests' name='TC_slotmap_del_unack' time='0.085376'/> <testcase classname='RemsimServer_Tests' name='TC_slotmap_del_active' time='1.063408'/> <testcase classname='RemsimServer_Tests' name='TC_slotmap_del_active_client' time='1.056912'/> <testcase classname='RemsimServer_Tests' name='TC_slotmap_add_active_bank' time='0.049423'> <failure type='fail-verdict'>Unexpected slotmaps: { clients := omit, banks := omit, slotmaps := { { bank := { bankId := 1, slotNr := 2 }, client := { clientId := 3, slotNr := 4 }, state := UNACKNOWLEDGED (1) } } } RemsimServer_Tests.ttcn:611 RemsimServer_Tests control part RemsimServer_Tests.ttcn:581 TC_slotmap_add_active_bank testcase </failure> </testcase> </testsuite> Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.