Build failed in Jenkins: ttcn3-hnbgw-test-latest #699

See https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/699/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 1.08 MB...] MC@4572189d1b33: Test execution finished. Execution of [EXECUTE] section finished. emtc MC@4572189d1b33: Terminating MTC. MC@4572189d1b33: MTC terminated. MC2> exit MC@4572189d1b33: Shutting down session. MC@4572189d1b33: Shutdown complete.

Comparing expected results '/osmo-ttcn3-hacks/hnbgw/expected-results.xml' against results in 'junit-xml-24.log' -------------------- pass HNBGW_Tests.TC_hnb_register pass HNBGW_Tests.TC_hnb_register_duplicate pass HNBGW_Tests.TC_hnb_register_duplicate_reuse_sctp_assoc pass HNBGW_Tests.TC_ue_register pass HNBGW_Tests.TC_ue_register_tmsi_lai pass->FAIL HNBGW_Tests.TC_ue_register_before_hnb_register pass HNBGW_Tests.TC_rab_release_abnormal pass HNBGW_Tests.TC_ranap_cs_initial_ue pass HNBGW_Tests.TC_ranap_ps_initial_ue pass HNBGW_Tests.TC_ranap_cs_initial_ue_empty_cr pass HNBGW_Tests.TC_ranap_ps_initial_ue_empty_cr pass HNBGW_Tests.TC_ranap_cs_bidir pass HNBGW_Tests.TC_ranap_ps_bidir pass HNBGW_Tests.TC_rab_assignment pass HNBGW_Tests.TC_rab_release pass HNBGW_Tests.TC_rab_release_abnormal pass HNBGW_Tests.TC_rab_assign_fail pass HNBGW_Tests.TC_rab_assign_mgcp_to pass->FAIL HNBGW_Tests.TC_rab_assign_mgw_iuup_addr_chg pass HNBGW_Tests.TC_ranap_cs_mo_disconnect pass HNBGW_Tests.TC_ranap_ps_mo_disconnect pass HNBGW_Tests.TC_ps_rab_assignment_without_pfcp pass HNBGW_Tests.TC_mscpool_L3Compl_on_1_cnlink pass HNBGW_Tests.TC_mscpool_L3Complete_by_imsi_round_robin pass HNBGW_Tests.TC_mscpool_LU_by_tmsi_null_nri_0_round_robin pass HNBGW_Tests.TC_mscpool_LU_by_tmsi_null_nri_1_round_robin pass HNBGW_Tests.TC_mscpool_L3Complete_by_tmsi_unassigned_nri_round_robin pass HNBGW_Tests.TC_mscpool_L3Complete_by_tmsi_valid_nri_msc_not_connected_round_robin pass HNBGW_Tests.TC_mscpool_L3Complete_by_tmsi_valid_nri_1 pass HNBGW_Tests.TC_mscpool_L3Complete_by_tmsi_valid_nri_2 pass HNBGW_Tests.TC_mscpool_LU_by_tmsi_from_other_PLMN pass HNBGW_Tests.TC_mscpool_paging_imsi pass HNBGW_Tests.TC_mscpool_paging_tmsi pass HNBGW_Tests.TC_mscpool_no_allow_attach_round_robin pass HNBGW_Tests.TC_mscpool_no_allow_attach_valid_nri pass HNBGW_Tests.TC_mscpool_sccp_n_pcstate_detaches_cnlink pass HNBGW_Tests.TC_mscpool_sccp_n_pcstate_attaches_cnlink pass HNBGW_Tests.TC_sgsnpool_L3Compl_on_1_cnlink pass HNBGW_Tests.TC_sgsnpool_L3Complete_no_nri_round_robin pass HNBGW_Tests.TC_sgsnpool_L3Complete_valid_nri_1 pass HNBGW_Tests.TC_sgsnpool_L3Complete_valid_nri_2 pass HNBGW_Tests.TC_sgsnpool_nri_from_other_PLMN pass HNBGW_Tests.TC_sgsnpool_sccp_n_pcstate_detaches_cnlink pass HNBGW_Tests.TC_sgsnpool_sccp_n_pcstate_attaches_cnlink pass HNBGW_Tests.TC_hnb_reregister_reuse_sctp_assoc pass HNBGW_Tests.TC_apply_sccp

Summary: pass->FAIL: 2 pass: 44 skip: 1

+ exit_code=0 + /osmo-ttcn3-hacks/log_merge.sh HNBGW_Tests --rm Generated HNBGW_Tests.TC_apply_sccp.merged Generated HNBGW_Tests.TC_hnb_register.merged Generated HNBGW_Tests.TC_hnb_register_duplicate.merged Generated HNBGW_Tests.TC_hnb_register_duplicate_reuse_sctp_assoc.merged Generated HNBGW_Tests.TC_hnb_reregister_reuse_sctp_assoc.merged Generated HNBGW_Tests.TC_mscpool_L3Compl_on_1_cnlink.merged Generated HNBGW_Tests.TC_mscpool_L3Complete_by_imsi_round_robin.merged Generated HNBGW_Tests.TC_mscpool_L3Complete_by_tmsi_unassigned_nri_round_robin.merged Generated HNBGW_Tests.TC_mscpool_L3Complete_by_tmsi_valid_nri_1.merged Generated HNBGW_Tests.TC_mscpool_L3Complete_by_tmsi_valid_nri_2.merged Generated HNBGW_Tests.TC_mscpool_L3Complete_by_tmsi_valid_nri_msc_not_connected_round_robin.merged Generated HNBGW_Tests.TC_mscpool_LU_by_tmsi_from_other_PLMN.merged Generated HNBGW_Tests.TC_mscpool_LU_by_tmsi_null_nri_0_round_robin.merged Generated HNBGW_Tests.TC_mscpool_LU_by_tmsi_null_nri_1_round_robin.merged Generated HNBGW_Tests.TC_mscpool_no_allow_attach_round_robin.merged Generated HNBGW_Tests.TC_mscpool_no_allow_attach_valid_nri.merged Generated HNBGW_Tests.TC_mscpool_paging_imsi.merged Generated HNBGW_Tests.TC_mscpool_paging_tmsi.merged Generated HNBGW_Tests.TC_mscpool_sccp_n_pcstate_attaches_cnlink.merged Generated HNBGW_Tests.TC_mscpool_sccp_n_pcstate_detaches_cnlink.merged Generated HNBGW_Tests.TC_ps_rab_assignment_without_pfcp.merged Generated HNBGW_Tests.TC_rab_assign_fail.merged Generated HNBGW_Tests.TC_rab_assign_mgcp_to.merged Generated HNBGW_Tests.TC_rab_assign_mgw_iuup_addr_chg.merged Generated HNBGW_Tests.TC_rab_assignment.merged Generated HNBGW_Tests.TC_rab_release.merged Generated HNBGW_Tests.TC_rab_release_abnormal.merged Generated HNBGW_Tests.TC_ranap_cs_bidir.merged Generated HNBGW_Tests.TC_ranap_cs_initial_ue.merged Generated HNBGW_Tests.TC_ranap_cs_initial_ue_empty_cr.merged Generated HNBGW_Tests.TC_ranap_cs_mo_disconnect.merged Generated HNBGW_Tests.TC_ranap_ps_bidir.merged Generated HNBGW_Tests.TC_ranap_ps_initial_ue.merged Generated HNBGW_Tests.TC_ranap_ps_initial_ue_empty_cr.merged Generated HNBGW_Tests.TC_ranap_ps_mo_disconnect.merged Generated HNBGW_Tests.TC_sgsnpool_L3Compl_on_1_cnlink.merged Generated HNBGW_Tests.TC_sgsnpool_L3Complete_no_nri_round_robin.merged Generated HNBGW_Tests.TC_sgsnpool_L3Complete_valid_nri_1.merged Generated HNBGW_Tests.TC_sgsnpool_L3Complete_valid_nri_2.merged Generated HNBGW_Tests.TC_sgsnpool_nri_from_other_PLMN.merged Generated HNBGW_Tests.TC_sgsnpool_sccp_n_pcstate_attaches_cnlink.merged Generated HNBGW_Tests.TC_sgsnpool_sccp_n_pcstate_detaches_cnlink.merged Generated HNBGW_Tests.TC_ue_register.merged Generated HNBGW_Tests.TC_ue_register_before_hnb_register.merged Generated HNBGW_Tests.TC_ue_register_tmsi_lai.merged Removing Input log files !!! + exit 0 + echo Stopping containers Stopping containers + docker_kill_wait jenkins-ttcn3-hnbgw-test-latest-699-hnbgw + docker kill jenkins-ttcn3-hnbgw-test-latest-699-hnbgw jenkins-ttcn3-hnbgw-test-latest-699-hnbgw + docker wait jenkins-ttcn3-hnbgw-test-latest-699-hnbgw 137 + docker_kill_wait jenkins-ttcn3-hnbgw-test-latest-699-stp + docker kill jenkins-ttcn3-hnbgw-test-latest-699-stp jenkins-ttcn3-hnbgw-test-latest-699-stp + docker wait jenkins-ttcn3-hnbgw-test-latest-699-stp 137 + echo Testing with PFCP Testing with PFCP + mkdir https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/699/artifact/logs/with-pfcp + run_tests https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/699/artifact/logs/with-pfcp with-pfcp/HNBGW_Tests.cfg osmo-stp.cfg with-pfcp/osmo-hnbgw.cfg + base_dir=https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/699/artifact/logs/with-pfcp + tests_cfg=with-pfcp/HNBGW_Tests.cfg + stp_cfg=osmo-stp.cfg + hnbgw_cfg=with-pfcp/osmo-hnbgw.cfg + mkdir https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/699/artifact/logs/with-pfcp/hnbgw-tester + mkdir https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/699/artifact/logs/with-pfcp/hnbgw-tester/unix + cp with-pfcp/HNBGW_Tests.cfg https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/699/artifact/logs/with-pfcp/hnbgw-tester/ + write_mp_osmo_repo https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/699/artifact/logs/with-pfcp/hnbgw-tester/HNBGW_Tests.cfg + local repo=nightly + local config=https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/699/artifact/logs/with-pfcp/hnbgw-tester/HNBGW_Tests.cfg + local line + [ -e https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/699/artifact/logs/with-pfcp/hnbgw-tester/HNBGW_Tests.cfg ] + repo=latest + line=Misc_Helpers.mp_osmo_repo := "latest" + sed -i s/[MODULE_PARAMETERS]/[MODULE_PARAMETERS]\nMisc_Helpers.mp_osmo_repo := "latest"/g https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/699/artifact/logs/with-pfcp/hnbgw-tester/HNBGW_Tests.cfg + mkdir https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/699/artifact/logs/with-pfcp/stp + cp osmo-stp.cfg https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/699/artifact/logs/with-pfcp/stp/osmo-stp.cfg + mkdir https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/699/artifact/logs/with-pfcp/hnbgw + mkdir https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/699/artifact/logs/with-pfcp/hnbgw/unix + cp with-pfcp/osmo-hnbgw.cfg https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/699/artifact/logs/with-pfcp/hnbgw/osmo-hnbgw.cfg + mkdir https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/699/artifact/logs/with-pfcp/unix + network_replace_subnet_in_configs + set +x Applying SUBNET=90 to: https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/699/artifact/logs/hnbgw-tester/HNBGW_Tests.cfg sed: couldn't open temporary file https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/699/artifact/logs/hnbgw-tester/seddYSagb: Permission denied

### Clean up ###

+ trap - EXIT INT TERM 0 + type clean_up + clean_up + sed -i s/classname='([^']+)'/classname='\1:with-pfcp'/g https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/699/artifact/logs/with-pfcp/hnbgw-tester/junit-xml-with-pfcp-*.log sed: can't read https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/699/artifact/logs/with-pfcp/hnbgw-tester/junit-xml-with-pfcp-*.log: No such file or directory + network_clean + docker network inspect ttcn3-hnbgw-test-90 + grep Name + cut -d : -f2 + awk -F" NR>1{print $2} + local containers= + [ -n ] + network_remove + set +x Removing network ttcn3-hnbgw-test-90 + docker network remove ttcn3-hnbgw-test-90 ttcn3-hnbgw-test-90 + rm -rf https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/699/artifact/logs/unix + fix_perms + set +x Fixing permissions + id -u + id -g + docker run --rm -v https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/ws/logs:/data -v https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/ws/_cache:/cache --name jenkins-ttcn3-hnbgw-test-latest-699-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-hnbgw-test-latest/699/artifact/logs/hnbgw-tester/junit-xml-24.log <?xml version="1.0"?> <testsuite name='Titan' tests='45' failures='2' errors='0' skipped='0' inconc='0' time='358.00'> <testcase classname='HNBGW_Tests' name='TC_hnb_register' time='1.094386'/> <testcase classname='HNBGW_Tests' name='TC_hnb_register_duplicate' time='1.109621'/> <testcase classname='HNBGW_Tests' name='TC_hnb_register_duplicate_reuse_sctp_assoc' time='1.122283'/> <testcase classname='HNBGW_Tests' name='TC_ue_register' time='1.102606'/> <testcase classname='HNBGW_Tests' name='TC_ue_register_tmsi_lai' time='1.101739'/> <testcase classname='HNBGW_Tests' name='TC_ue_register_before_hnb_register' time='3.100150'> <failure type='fail-verdict'>Timeout waiting for UE Register response HNBGW_Tests.ttcn:2887 HNBGW_Tests control part HNBGW_Tests.ttcn:1047 TC_ue_register_before_hnb_register testcase </failure> </testcase> <testcase classname='HNBGW_Tests' name='TC_ranap_cs_initial_ue' time='2.159230'/> <testcase classname='HNBGW_Tests' name='TC_ranap_ps_initial_ue' time='2.163066'/> <testcase classname='HNBGW_Tests' name='TC_ranap_cs_initial_ue_empty_cr' time='2.172342'/> <testcase classname='HNBGW_Tests' name='TC_ranap_ps_initial_ue_empty_cr' time='2.185328'/> <testcase classname='HNBGW_Tests' name='TC_ranap_cs_bidir' time='2.375680'/> <testcase classname='HNBGW_Tests' name='TC_ranap_ps_bidir' time='2.423588'/> <testcase classname='HNBGW_Tests' name='TC_rab_assignment' time='2.468542'/> <testcase classname='HNBGW_Tests' name='TC_rab_release' time='2.461553'/> <testcase classname='HNBGW_Tests' name='TC_rab_release_abnormal' time='2.443274'/> <testcase classname='HNBGW_Tests' name='TC_rab_assign_fail' time='2.434444'/> <testcase classname='HNBGW_Tests' name='TC_rab_assign_mgcp_to' time='6.394827'/> <testcase classname='HNBGW_Tests' name='TC_rab_assign_mgw_iuup_addr_chg' time='7.439592'> <failure type='fail-verdict'>Timeout waiting for Iuh { initiatingMessage := { procedureCode := 0, criticality := reject (0), value_ := { rAB_AssignmentRequest := { protocolIEs := { { id := 54, criticality := ignore (1), value_ := { rAB_SetupOrModifyList := { { { id := 53, firstCriticality := reject (0), firstValue := { rAB_SetupOrModifyItemFirst := { rAB_ID := '00010111'B, nAS_SynchronisationIndicator := omit, rAB_Parameters := { trafficClass := conversational (0), rAB_AsymmetryIndicator := symmetric_bidirectional (0), maxBitrate := { 12200 }, guaranteedBitRate := { 12200 }, deliveryOrder := delivery_order_requested (0), maxSDU_Size := 244, sDU_Parameters := { { sDU_ErrorRatio := { mantissa := 1, exponent := 5, iE_Extensions := omit }, residualBitErrorRatio := { mantissa := 1, exponent := 6, iE_Extensions := omit }, deliveryOfErroneousSDU := yes (0), sDU_FormatInformationParameters := { { subflowSDU_Size := 81, rAB_SubflowCombinationBitRate := omit, iE_Extensions := omit }, { subflowSDU_Size := 39, rAB_SubflowCombinationBitRate := omit, iE_Extensions := omit } }, iE_Extensions := omit }, { sDU_ErrorRatio := omit, residualBitErrorRatio := { mantissa := 1, exponent := 3, iE_Extensions := omit }, deliveryOfErroneousSDU := no_error_detection_consideration (2), sDU_FormatInformationParameters := { { subflowSDU_Size := 103, rAB_SubflowCombinationBitRate := omit, iE_Extensions := omit }, { subflowSDU_Size := 0, rAB_SubflowCombinationBitRate := omit, iE_Extensions := omit } }, iE_Extensions := omit }, { sDU_ErrorRatio := omit, residualBitErrorRatio := { mantissa := 5, exponent := 3, iE_Extensions := omit }, deliveryOfErroneousSDU := no_error_detection_consideration (2), sDU_FormatInformationParameters := { { subflowSDU_Size := 60, rAB_SubflowCombinationBitRate := omit, iE_Extensions := omit }, { subflowSDU_Size := 0, rAB_SubflowCombinationBitRate := omit, iE_Extensions := omit } }, iE_Extensions := omit } }, transferDelay := 80, trafficHandlingPriority := omit, allocationOrRetentionPriority := { priorityLevel := 15, pre_emptionCapability := shall_not_trigger_pre_emption (0), pre_emptionVulnerability := pre_emptable (1), queuingAllowed := queueing_not_allowed (0), iE_Extensions := omit }, sourceStatisticsDescriptor := omit, relocationRequirement := omit, iE_Extensions := omit }, userPlaneInformation := { userPlaneMode := support_mode_for_predefined_SDU_sizes (1), uP_ModeVersions := '0000000000000010'B, iE_Extensions := omit }, transportLayerInformation := { transportLayerAddress := '0011010100000000000000010111111100000011000000100000000100000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000'B, iuTransportAssociation := { bindingID := '27100000'O }, iE_Extensions := omit }, service_Handover := omit, iE_Extensions := omit } }, secondCriticality := ignore (1), secondValue := { rAB_SetupOrModifyItemSecond := { pDP_TypeInformation := omit, dataVolumeReportingIndication := omit, dl_GTP_PDU_SequenceNumber := omit, ul_GTP_PDU_SequenceNumber := omit, dl_N_PDU_SequenceNumber := omit, ul_N_PDU_SequenceNumber := omit, iE_Extensions := omit } } } } } } } }, protocolExtensions := omit } } } } HNBGW_Tests.ttcn:2899 HNBGW_Tests control part HNBGW_Tests.ttcn:1689 TC_rab_assign_mgw_iuup_addr_chg testcase </failure> </testcase> <testcase classname='HNBGW_Tests' name='TC_ranap_cs_mo_disconnect' time='7.592056'/> <testcase classname='HNBGW_Tests' name='TC_ranap_ps_mo_disconnect' time='7.587177'/> <testcase classname='HNBGW_Tests' name='TC_ps_rab_assignment_without_pfcp' time='7.471781'/> <testcase classname='HNBGW_Tests' name='TC_mscpool_L3Compl_on_1_cnlink' time='5.395224'/> <testcase classname='HNBGW_Tests' name='TC_mscpool_L3Complete_by_imsi_round_robin' time='5.403498'/> <testcase classname='HNBGW_Tests' name='TC_mscpool_LU_by_tmsi_null_nri_0_round_robin' time='5.415666'/> <testcase classname='HNBGW_Tests' name='TC_mscpool_LU_by_tmsi_null_nri_1_round_robin' time='5.342003'/> <testcase classname='HNBGW_Tests' name='TC_mscpool_L3Complete_by_tmsi_unassigned_nri_round_robin' time='5.411156'/> <testcase classname='HNBGW_Tests' name='TC_mscpool_L3Complete_by_tmsi_valid_nri_msc_not_connected_round_robin' time='5.451692'/> <testcase classname='HNBGW_Tests' name='TC_mscpool_L3Complete_by_tmsi_valid_nri_1' time='5.379724'/> <testcase classname='HNBGW_Tests' name='TC_mscpool_L3Complete_by_tmsi_valid_nri_2' time='5.366797'/> <testcase classname='HNBGW_Tests' name='TC_mscpool_LU_by_tmsi_from_other_PLMN' time='4.399865'/> <testcase classname='HNBGW_Tests' name='TC_mscpool_paging_imsi' time='5.268654'/> <testcase classname='HNBGW_Tests' name='TC_mscpool_paging_tmsi' time='5.290024'/> <testcase classname='HNBGW_Tests' name='TC_mscpool_no_allow_attach_round_robin' time='5.416659'/> <testcase classname='HNBGW_Tests' name='TC_mscpool_no_allow_attach_valid_nri' time='5.418551'/> <testcase classname='HNBGW_Tests' name='TC_mscpool_sccp_n_pcstate_detaches_cnlink' time='5.370956'/> <testcase classname='HNBGW_Tests' name='TC_mscpool_sccp_n_pcstate_attaches_cnlink' time='6.405255'/> <testcase classname='HNBGW_Tests' name='TC_sgsnpool_L3Compl_on_1_cnlink' time='5.412799'/> <testcase classname='HNBGW_Tests' name='TC_sgsnpool_L3Complete_no_nri_round_robin' time='5.399794'/> <testcase classname='HNBGW_Tests' name='TC_sgsnpool_L3Complete_valid_nri_1' time='5.392447'/> <testcase classname='HNBGW_Tests' name='TC_sgsnpool_L3Complete_valid_nri_2' time='5.406236'/> <testcase classname='HNBGW_Tests' name='TC_sgsnpool_nri_from_other_PLMN' time='4.370553'/> <testcase classname='HNBGW_Tests' name='TC_sgsnpool_sccp_n_pcstate_detaches_cnlink' time='5.397806'/> <testcase classname='HNBGW_Tests' name='TC_sgsnpool_sccp_n_pcstate_attaches_cnlink' time='6.406301'/> <testcase classname='HNBGW_Tests' name='TC_hnb_reregister_reuse_sctp_assoc' time='1.325480'/> <testcase classname='HNBGW_Tests' name='TC_apply_sccp' time='6.255937'/> </testsuite> Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.