See <https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1141/display/redirect?page=changes> Changes: [Pau Espin Pedrol] ttcn3-asterisk: Enable debug in log file [Pau Espin Pedrol] ttcn3-asterisk: Enable debug for pjproject [Pau Espin Pedrol] ttcn3-asterisk: Configure asterisk for initial SIP register on IMS side [Pau Espin Pedrol] ttcn3-docker-run.sh: Fix dumpcap failing to access /data inside docker ------------------------------------------ [...truncated 230.56 KB...] Waiting for packet dumper to finish... 0 (prev_count=-1, count=24) Waiting for packet dumper to finish... 1 (prev_count=24, count=2205) MTC@a9e49a2d5d3b: External command `../ttcn3-tcpdump-stop.sh RemsimServer_Tests.TC_slotmap_del_nonexistant pass' was executed successfully (exit status: 0). MTC@a9e49a2d5d3b: Starting external command `../ttcn3-tcpdump-start.sh RemsimServer_Tests.TC_slotmap_del_unack'. ------ RemsimServer_Tests.TC_slotmap_del_unack ------ Thu May 16 09:35:00 UTC 2024 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@a9e49a2d5d3b: External command `../ttcn3-tcpdump-start.sh RemsimServer_Tests.TC_slotmap_del_unack' was executed successfully (exit status: 0). MTC@a9e49a2d5d3b: Test case TC_slotmap_del_unack started. RSPRO0(14)@a9e49a2d5d3b: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } MTC@a9e49a2d5d3b: Warning: Re-starting timer T, which is already active (running or expired). RSPRO0(14)@a9e49a2d5d3b: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO0(14)@a9e49a2d5d3b: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO0(14)@a9e49a2d5d3b: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } MTC@a9e49a2d5d3b: setverdict(pass): none -> pass MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed RSPRO0(14)@a9e49a2d5d3b: Final verdict of PTC: none MTC@a9e49a2d5d3b: Setting final verdict of the test case. MTC@a9e49a2d5d3b: Local verdict of MTC: pass MTC@a9e49a2d5d3b: Local verdict of PTC RSPRO0(14): none (pass -> pass) MTC@a9e49a2d5d3b: Test case TC_slotmap_del_unack finished. Verdict: pass MTC@a9e49a2d5d3b: Starting external command `../ttcn3-tcpdump-stop.sh RemsimServer_Tests.TC_slotmap_del_unack pass'. Thu May 16 09:35:01 UTC 2024 ====== RemsimServer_Tests.TC_slotmap_del_unack pass ====== Waiting for packet dumper to finish... 0 (prev_count=-1, count=4599) Waiting for packet dumper to finish... 1 (prev_count=4599, count=9060) MTC@a9e49a2d5d3b: External command `../ttcn3-tcpdump-stop.sh RemsimServer_Tests.TC_slotmap_del_unack pass' was executed successfully (exit status: 0). MTC@a9e49a2d5d3b: Starting external command `../ttcn3-tcpdump-start.sh RemsimServer_Tests.TC_slotmap_del_active'. ------ RemsimServer_Tests.TC_slotmap_del_active ------ Thu May 16 09:35:03 UTC 2024 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@a9e49a2d5d3b: External command `../ttcn3-tcpdump-start.sh RemsimServer_Tests.TC_slotmap_del_active' was executed successfully (exit status: 0). MTC@a9e49a2d5d3b: Test case TC_slotmap_del_active started. RSPRO0(15)@a9e49a2d5d3b: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } MTC@a9e49a2d5d3b: Warning: Re-starting timer T, which is already active (running or expired). RSPRO0(15)@a9e49a2d5d3b: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO0(15)@a9e49a2d5d3b: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO0(15)@a9e49a2d5d3b: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } MTC@a9e49a2d5d3b: setverdict(pass): none -> pass MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed RSPRO0(15)@a9e49a2d5d3b: Final verdict of PTC: none MTC@a9e49a2d5d3b: Setting final verdict of the test case. MTC@a9e49a2d5d3b: Local verdict of MTC: pass MTC@a9e49a2d5d3b: Local verdict of PTC RSPRO0(15): none (pass -> pass) MTC@a9e49a2d5d3b: Test case TC_slotmap_del_active finished. Verdict: pass MTC@a9e49a2d5d3b: Starting external command `../ttcn3-tcpdump-stop.sh RemsimServer_Tests.TC_slotmap_del_active pass'. Thu May 16 09:35:06 UTC 2024 ====== RemsimServer_Tests.TC_slotmap_del_active pass ====== Waiting for packet dumper to finish... 0 (prev_count=-1, count=6787) Waiting for packet dumper to finish... 1 (prev_count=6787, count=9563) MTC@a9e49a2d5d3b: External command `../ttcn3-tcpdump-stop.sh RemsimServer_Tests.TC_slotmap_del_active pass' was executed successfully (exit status: 0). MTC@a9e49a2d5d3b: Starting external command `../ttcn3-tcpdump-start.sh RemsimServer_Tests.TC_slotmap_del_active_client'. ------ RemsimServer_Tests.TC_slotmap_del_active_client ------ Thu May 16 09:35:08 UTC 2024 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@a9e49a2d5d3b: External command `../ttcn3-tcpdump-start.sh RemsimServer_Tests.TC_slotmap_del_active_client' was executed successfully (exit status: 0). MTC@a9e49a2d5d3b: Test case TC_slotmap_del_active_client started. RSPRO0(16)@a9e49a2d5d3b: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } MTC@a9e49a2d5d3b: Warning: Re-starting timer T, which is already active (running or expired). RSPRO0(16)@a9e49a2d5d3b: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO0(16)@a9e49a2d5d3b: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO0(16)@a9e49a2d5d3b: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO1(17)@a9e49a2d5d3b: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } MTC@a9e49a2d5d3b: Warning: Re-starting timer T, which is already active (running or expired). RSPRO1(17)@a9e49a2d5d3b: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO1(17)@a9e49a2d5d3b: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO1(17)@a9e49a2d5d3b: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } MTC@a9e49a2d5d3b: setverdict(pass): none -> pass MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(fail): pass -> fail reason: "Unexpected slotmaps: { clients := omit, banks := omit, slotmaps := { { bank := { bankId := 1, slotNr := 2 }, client := { clientId := 3, slotNr := 4 }, state := UNACKNOWLEDGED (1) } } }", new component reason: "Unexpected slotmaps: { clients := omit, banks := omit, slotmaps := { { bank := { bankId := 1, slotNr := 2 }, client := { clientId := 3, slotNr := 4 }, state := UNACKNOWLEDGED (1) } } }" RSPRO0(16)@a9e49a2d5d3b: Final verdict of PTC: none RSPRO1(17)@a9e49a2d5d3b: Final verdict of PTC: none MTC@a9e49a2d5d3b: Setting final verdict of the test case. MTC@a9e49a2d5d3b: Local verdict of MTC: fail reason: "Unexpected slotmaps: { clients := omit, banks := omit, slotmaps := { { bank := { bankId := 1, slotNr := 2 }, client := { clientId := 3, slotNr := 4 }, state := UNACKNOWLEDGED (1) } } }" MTC@a9e49a2d5d3b: Local verdict of PTC RSPRO0(16): none (fail -> fail) MTC@a9e49a2d5d3b: Local verdict of PTC RSPRO1(17): none (fail -> fail) MTC@a9e49a2d5d3b: Test case TC_slotmap_del_active_client finished. Verdict: fail reason: Unexpected slotmaps: { clients := omit, banks := omit, slotmaps := { { bank := { bankId := 1, slotNr := 2 }, client := { clientId := 3, slotNr := 4 }, state := UNACKNOWLEDGED (1) } } } MTC@a9e49a2d5d3b: Starting external command `../ttcn3-tcpdump-stop.sh RemsimServer_Tests.TC_slotmap_del_active_client fail'. Thu May 16 09:35:10 UTC 2024 ------ RemsimServer_Tests.TC_slotmap_del_active_client fail ------ Waiting for packet dumper to finish... 0 (prev_count=-1, count=8924) Waiting for packet dumper to finish... 1 (prev_count=8924, count=12374) MTC@a9e49a2d5d3b: External command `../ttcn3-tcpdump-stop.sh RemsimServer_Tests.TC_slotmap_del_active_client fail' was executed successfully (exit status: 0). MTC@a9e49a2d5d3b: Starting external command `../ttcn3-tcpdump-start.sh RemsimServer_Tests.TC_slotmap_add_active_bank'. ------ RemsimServer_Tests.TC_slotmap_add_active_bank ------ Thu May 16 09:35:12 UTC 2024 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@a9e49a2d5d3b: External command `../ttcn3-tcpdump-start.sh RemsimServer_Tests.TC_slotmap_add_active_bank' was executed successfully (exit status: 0). MTC@a9e49a2d5d3b: Test case TC_slotmap_add_active_bank started. RSPRO0(18)@a9e49a2d5d3b: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } MTC@a9e49a2d5d3b: Warning: Re-starting timer T, which is already active (running or expired). RSPRO0(18)@a9e49a2d5d3b: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO0(18)@a9e49a2d5d3b: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO0(18)@a9e49a2d5d3b: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } MTC@a9e49a2d5d3b: setverdict(pass): none -> pass MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed MTC@a9e49a2d5d3b: setverdict(pass): pass -> pass, component reason not changed RSPRO0(18)@a9e49a2d5d3b: Final verdict of PTC: none MTC@a9e49a2d5d3b: Setting final verdict of the test case. MTC@a9e49a2d5d3b: Local verdict of MTC: pass MTC@a9e49a2d5d3b: Local verdict of PTC RSPRO0(18): none (pass -> pass) MTC@a9e49a2d5d3b: Test case TC_slotmap_add_active_bank finished. Verdict: pass MTC@a9e49a2d5d3b: Starting external command `../ttcn3-tcpdump-stop.sh RemsimServer_Tests.TC_slotmap_add_active_bank pass'. Thu May 16 09:35:13 UTC 2024 ====== RemsimServer_Tests.TC_slotmap_add_active_bank pass ====== Waiting for packet dumper to finish... 0 (prev_count=-1, count=2571) Waiting for packet dumper to finish... 1 (prev_count=2571, count=6102) MTC@a9e49a2d5d3b: External command `../ttcn3-tcpdump-stop.sh RemsimServer_Tests.TC_slotmap_add_active_bank pass' was executed successfully (exit status: 0). MC@a9e49a2d5d3b: Test execution finished. Execution of [EXECUTE] section finished. emtc MC@a9e49a2d5d3b: Terminating MTC. MC@a9e49a2d5d3b: MTC terminated. MC2> exit MC@a9e49a2d5d3b: Shutting down session. MC@a9e49a2d5d3b: 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_conn_cl_b_slotmap_add.merged Generated REMSIM_Tests.TC_connect_and_nothing.merged Generated REMSIM_Tests.TC_connect_bank.merged Generated REMSIM_Tests.TC_connect_bank_duplicate.merged Generated REMSIM_Tests.TC_connect_client.merged Generated REMSIM_Tests.TC_connect_client_duplicate.merged Generated REMSIM_Tests.TC_slotmap_add.merged Generated REMSIM_Tests.TC_slotmap_add_active_bank.merged Generated REMSIM_Tests.TC_slotmap_add_conn_cl_b.merged Generated REMSIM_Tests.TC_slotmap_add_out_of_range.merged Generated REMSIM_Tests.TC_slotmap_del_active.merged Generated REMSIM_Tests.TC_slotmap_del_active_client.merged Generated REMSIM_Tests.TC_slotmap_del_new.merged Generated REMSIM_Tests.TC_slotmap_del_nonexistant.merged Generated REMSIM_Tests.TC_slotmap_del_unack.merged Removing Input log files !!! + exit 0 + docker_kill_wait jenkins-ttcn3-remsim-test-latest-1141-server + docker kill jenkins-ttcn3-remsim-test-latest-1141-server jenkins-ttcn3-remsim-test-latest-1141-server + docker wait jenkins-ttcn3-remsim-test-latest-1141-server Error response from daemon: No such container: jenkins-ttcn3-remsim-test-latest-1141-server + true + echo Changing to bankd configuration Changing to bankd configuration + cp bankd/REMSIM_Tests.cfg <https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1141/artifact/logs/remsim-tester/> cp: cannot create regular file '<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1141/artifact/logs/remsim-tester/REMSIM_Tests.cfg'>: Permission denied + 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-199 + awk -F" NR>1{print $2} + + cutgrep -d : Name -f2 + local containers= + [ -n ] + network_remove + set +x Removing network ttcn3-remsim-test-199 + docker network remove ttcn3-remsim-test-199 ttcn3-remsim-test-199 + rm -rf <https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1141/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-latest/ws/logs>:/data -v <https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/ws/_cache>:/cache --name jenkins-ttcn3-remsim-test-latest-1141-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-latest/1141/artifact/logs/remsim-tester/junit-xml-server-24.log> <?xml version="1.0"?> <testsuite name='Titan' tests='15' failures='3' errors='0' skipped='0' inconc='0' time='59.00'> <testcase classname='RemsimServer_Tests' name='TC_connect_and_nothing' time='10.016534'/> <testcase classname='RemsimServer_Tests' name='TC_connect_client' time='0.057875'/> <testcase classname='RemsimServer_Tests' name='TC_connect_client_duplicate' time='0.101362'> <failure type='fail-verdict'>Received unexpected RPSRO{ version := 2, tag := 2342, msg := { connectClientRes := { identity := { type_ := remsimServer (1), name := "fixme-name", software := "remsim-server", swVersion := "1.0.0", hwManufacturer := omit, hwModel := omit, hwSerialNr := omit, hwVersion := omit, fwVersion := omit }, result := ok (0) } } } RemsimServer_Tests.ttcn:599 RemsimServer_Tests control part RemsimServer_Tests.ttcn:167 TC_connect_client_duplicate testcase </failure> </testcase> <testcase classname='RemsimServer_Tests' name='TC_connect_bank' time='0.057771'/> <testcase classname='RemsimServer_Tests' name='TC_connect_bank_duplicate' time='0.110353'> <failure type='fail-verdict'>Received unexpected RPSRO{ version := 2, tag := 0, msg := { connectBankRes := { identity := { type_ := remsimServer (1), name := "fixme-name", software := "remsim-server", swVersion := "1.0.0", hwManufacturer := omit, hwModel := omit, hwSerialNr := omit, hwVersion := omit, fwVersion := omit }, result := ok (0) } } } RemsimServer_Tests.ttcn:601 RemsimServer_Tests control part RemsimServer_Tests.ttcn:232 TC_connect_bank_duplicate testcase </failure> </testcase> <testcase classname='RemsimServer_Tests' name='TC_slotmap_add' time='0.005678'/> <testcase classname='RemsimServer_Tests' name='TC_slotmap_add_conn_cl_b' time='0.106989'/> <testcase classname='RemsimServer_Tests' name='TC_slotmap_add_out_of_range' time='0.008494'/> <testcase classname='RemsimServer_Tests' name='TC_conn_cl_b_slotmap_add' time='0.101924'/> <testcase classname='RemsimServer_Tests' name='TC_slotmap_del_new' time='0.005631'/> <testcase classname='RemsimServer_Tests' name='TC_slotmap_del_nonexistant' time='0.003221'/> <testcase classname='RemsimServer_Tests' name='TC_slotmap_del_unack' time='0.099417'/> <testcase classname='RemsimServer_Tests' name='TC_slotmap_del_active' time='1.108771'/> <testcase classname='RemsimServer_Tests' name='TC_slotmap_del_active_client' time='1.113535'> <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:610 RemsimServer_Tests control part RemsimServer_Tests.ttcn:554 TC_slotmap_del_active_client testcase </failure> </testcase> <testcase classname='RemsimServer_Tests' name='TC_slotmap_add_active_bank' time='0.097899'/> </testsuite> Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.